[23:17:04.618] New invocation is queued and will start shortly
[23:17:05.707] Starting the invocation (attempt 1)
[23:17:05.748] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:17:05.782] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:17:05.782] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9083764812133495168",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9083764812133495168",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b",
"gitiles_ref:refs/heads/master"
]
}
[23:17:07.093] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "project:chromium",
"created_ts": "1553210225943355",
"id": "8918334750080198848",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1553210226900720",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11488",
"builder:linux-chromeos-dbg",
"buildset:commit/git/3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083764812133495168",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8918334750080198848/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43bb5cbf381e1c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553210226900835",
"url": "https://ci.chromium.org/b/8918334750080198848",
"utcnow_ts": "1553210227081028"
}
}
[23:17:07.093] Task URL: https://ci.chromium.org/b/8918334750080198848
[23:17:07.093] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:2:0) after 1m0s
[23:18:07.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:2:0)
[23:18:07.067] Timer tick, asking Buildbucket for the build status
[23:18:07.321] Build 8918334750080198848: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:07.321] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:3:0) after 1m0s
[23:18:59.836] Received PubSub notification, asking Buildbucket for the build status
[23:18:59.999] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:07.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:3:0)
[23:19:07.297] Timer tick, asking Buildbucket for the build status
[23:19:07.397] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:07.397] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:5:0) after 1m0s
[23:20:07.415] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:5:0)
[23:20:07.415] Timer tick, asking Buildbucket for the build status
[23:20:07.546] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:07.546] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:6:0) after 1m0s
[23:21:07.635] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:6:0)
[23:21:07.635] Timer tick, asking Buildbucket for the build status
[23:21:07.900] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:07.900] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:7:0) after 1m0s
[23:22:07.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:7:0)
[23:22:07.891] Timer tick, asking Buildbucket for the build status
[23:22:08.260] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:08.260] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:8:0) after 1m0s
[23:23:08.278] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:8:0)
[23:23:08.278] Timer tick, asking Buildbucket for the build status
[23:23:08.679] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:08.679] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:9:0) after 1m0s
[23:24:08.697] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:9:0)
[23:24:08.697] Timer tick, asking Buildbucket for the build status
[23:24:09.655] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:09.655] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:10:0) after 1m0s
[23:25:09.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:10:0)
[23:25:09.675] Timer tick, asking Buildbucket for the build status
[23:25:09.931] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:09.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:11:0) after 1m0s
[23:26:09.903] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:11:0)
[23:26:09.903] Timer tick, asking Buildbucket for the build status
[23:26:10.168] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:10.168] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:12:0) after 1m0s
[23:27:10.206] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:12:0)
[23:27:10.206] Timer tick, asking Buildbucket for the build status
[23:27:10.454] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:10.454] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:13:0) after 1m0s
[23:28:10.503] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:13:0)
[23:28:10.503] Timer tick, asking Buildbucket for the build status
[23:28:11.423] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:11.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:14:0) after 1m0s
[23:29:11.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:14:0)
[23:29:11.408] Timer tick, asking Buildbucket for the build status
[23:29:11.718] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:11.718] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:15:0) after 1m0s
[23:30:11.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:15:0)
[23:30:11.739] Timer tick, asking Buildbucket for the build status
[23:30:11.871] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:11.871] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:16:0) after 1m0s
[23:31:11.891] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:16:0)
[23:31:11.891] Timer tick, asking Buildbucket for the build status
[23:31:11.975] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:11.975] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:17:0) after 1m0s
[23:32:11.976] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:17:0)
[23:32:11.976] Timer tick, asking Buildbucket for the build status
[23:32:12.070] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:12.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:18:0) after 1m0s
[23:33:12.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:18:0)
[23:33:12.093] Timer tick, asking Buildbucket for the build status
[23:33:12.185] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:12.185] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:19:0) after 1m0s
[23:34:12.205] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:19:0)
[23:34:12.205] Timer tick, asking Buildbucket for the build status
[23:34:12.477] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:12.477] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:20:0) after 1m0s
[23:35:12.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:20:0)
[23:35:12.497] Timer tick, asking Buildbucket for the build status
[23:35:13.402] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:13.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:21:0) after 1m0s
[23:36:13.399] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:21:0)
[23:36:13.399] Timer tick, asking Buildbucket for the build status
[23:36:13.702] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:13.702] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:22:0) after 1m0s
[23:37:13.676] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:22:0)
[23:37:13.676] Timer tick, asking Buildbucket for the build status
[23:37:13.801] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:13.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:23:0) after 1m0s
[23:38:13.787] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:23:0)
[23:38:13.787] Timer tick, asking Buildbucket for the build status
[23:38:13.905] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:13.905] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:24:0) after 1m0s
[23:39:13.894] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:24:0)
[23:39:13.894] Timer tick, asking Buildbucket for the build status
[23:39:14.117] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:14.117] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:25:0) after 1m0s
[23:40:14.146] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:25:0)
[23:40:14.146] Timer tick, asking Buildbucket for the build status
[23:40:14.635] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:14.635] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:26:0) after 1m0s
[23:41:14.659] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:26:0)
[23:41:14.659] Timer tick, asking Buildbucket for the build status
[23:41:14.829] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:14.829] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:27:0) after 1m0s
[23:42:14.806] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:27:0)
[23:42:14.806] Timer tick, asking Buildbucket for the build status
[23:42:15.567] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:15.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:28:0) after 1m0s
[23:43:15.592] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:28:0)
[23:43:15.592] Timer tick, asking Buildbucket for the build status
[23:43:15.682] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:15.682] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:29:0) after 1m0s
[23:44:15.690] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:29:0)
[23:44:15.690] Timer tick, asking Buildbucket for the build status
[23:44:15.801] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:15.801] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:30:0) after 1m0s
[23:45:15.773] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:30:0)
[23:45:15.773] Timer tick, asking Buildbucket for the build status
[23:45:15.865] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:15.865] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:31:0) after 1m0s
[23:46:15.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:31:0)
[23:46:15.880] Timer tick, asking Buildbucket for the build status
[23:46:16.085] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:16.085] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:32:0) after 1m0s
[23:47:16.113] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:32:0)
[23:47:16.113] Timer tick, asking Buildbucket for the build status
[23:47:16.762] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:16.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:33:0) after 1m0s
[23:48:16.781] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:33:0)
[23:48:16.781] Timer tick, asking Buildbucket for the build status
[23:48:17.322] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:17.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:34:0) after 1m0s
[23:49:17.357] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:34:0)
[23:49:17.357] Timer tick, asking Buildbucket for the build status
[23:49:17.491] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:17.491] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:35:0) after 1m0s
[23:50:17.491] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:35:0)
[23:50:17.491] Timer tick, asking Buildbucket for the build status
[23:50:17.797] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:17.797] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:36:0) after 1m0s
[23:51:17.815] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:36:0)
[23:51:17.815] Timer tick, asking Buildbucket for the build status
[23:51:18.405] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:18.405] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:37:0) after 1m0s
[23:52:18.405] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:37:0)
[23:52:18.405] Timer tick, asking Buildbucket for the build status
[23:52:18.616] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:18.616] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:38:0) after 1m0s
[23:53:18.633] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:38:0)
[23:53:18.633] Timer tick, asking Buildbucket for the build status
[23:53:18.876] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:18.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:39:0) after 1m0s
[23:54:18.875] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:39:0)
[23:54:18.876] Timer tick, asking Buildbucket for the build status
[23:54:19.707] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:19.707] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:40:0) after 1m0s
[23:55:19.730] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:40:0)
[23:55:19.730] Timer tick, asking Buildbucket for the build status
[23:55:20.407] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:20.407] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:41:0) after 1m0s
[23:56:20.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:41:0)
[23:56:20.428] Timer tick, asking Buildbucket for the build status
[23:56:20.641] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:20.641] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:42:0) after 1m0s
[23:57:20.634] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:42:0)
[23:57:20.634] Timer tick, asking Buildbucket for the build status
[23:57:20.837] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:20.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:43:0) after 1m0s
[23:58:20.852] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:43:0)
[23:58:20.852] Timer tick, asking Buildbucket for the build status
[23:58:21.499] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:21.499] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:44:0) after 1m0s
[23:59:21.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:44:0)
[23:59:21.484] Timer tick, asking Buildbucket for the build status
[23:59:21.712] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:21.712] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:45:0) after 1m0s
[00:00:21.736] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:45:0)
[00:00:21.736] Timer tick, asking Buildbucket for the build status
[00:00:21.952] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:21.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:46:0) after 1m0s
[00:01:21.957] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:46:0)
[00:01:21.957] Timer tick, asking Buildbucket for the build status
[00:01:23.125] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:23.125] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:47:0) after 1m0s
[00:02:23.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:47:0)
[00:02:23.154] Timer tick, asking Buildbucket for the build status
[00:02:24.003] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:24.003] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:48:0) after 1m0s
[00:03:24.000] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:48:0)
[00:03:24.000] Timer tick, asking Buildbucket for the build status
[00:03:24.217] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:24.217] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:49:0) after 1m0s
[00:04:24.257] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:49:0)
[00:04:24.257] Timer tick, asking Buildbucket for the build status
[00:04:24.392] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:24.392] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:50:0) after 1m0s
[00:05:24.384] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:50:0)
[00:05:24.384] Timer tick, asking Buildbucket for the build status
[00:05:25.037] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:25.037] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:51:0) after 1m0s
[00:06:25.062] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:51:0)
[00:06:25.062] Timer tick, asking Buildbucket for the build status
[00:06:25.457] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:25.457] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:52:0) after 1m0s
[00:07:25.476] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:52:0)
[00:07:25.476] Timer tick, asking Buildbucket for the build status
[00:07:25.765] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:25.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:53:0) after 1m0s
[00:08:25.786] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:53:0)
[00:08:25.786] Timer tick, asking Buildbucket for the build status
[00:08:26.239] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:26.239] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:54:0) after 1m0s
[00:09:26.314] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:54:0)
[00:09:26.314] Timer tick, asking Buildbucket for the build status
[00:09:26.954] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:26.954] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:55:0) after 1m0s
[00:10:26.980] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:55:0)
[00:10:26.980] Timer tick, asking Buildbucket for the build status
[00:10:27.244] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:27.244] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:56:0) after 1m0s
[00:11:27.229] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:56:0)
[00:11:27.229] Timer tick, asking Buildbucket for the build status
[00:11:27.416] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:27.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:57:0) after 1m0s
[00:12:27.551] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:57:0)
[00:12:27.551] Timer tick, asking Buildbucket for the build status
[00:12:28.110] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:28.110] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:58:0) after 1m0s
[00:13:28.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:58:0)
[00:13:28.190] Timer tick, asking Buildbucket for the build status
[00:13:29.035] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:29.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:59:0) after 1m0s
[00:14:29.058] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:59:0)
[00:14:29.058] Timer tick, asking Buildbucket for the build status
[00:14:29.206] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:29.206] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:60:0) after 1m0s
[00:15:29.181] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:60:0)
[00:15:29.181] Timer tick, asking Buildbucket for the build status
[00:15:29.605] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:29.606] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:61:0) after 1m0s
[00:16:29.579] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:61:0)
[00:16:29.579] Timer tick, asking Buildbucket for the build status
[00:16:29.762] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:29.762] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:62:0) after 1m0s
[00:17:29.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:62:0)
[00:17:29.748] Timer tick, asking Buildbucket for the build status
[00:17:29.880] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:29.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:63:0) after 1m0s
[00:18:29.885] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:63:0)
[00:18:29.885] Timer tick, asking Buildbucket for the build status
[00:18:30.004] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:30.004] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:64:0) after 1m0s
[00:19:30.050] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:64:0)
[00:19:30.050] Timer tick, asking Buildbucket for the build status
[00:19:30.222] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:30.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:65:0) after 1m0s
[00:20:30.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:65:0)
[00:20:30.200] Timer tick, asking Buildbucket for the build status
[00:20:30.419] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:30.419] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:66:0) after 1m0s
[00:21:30.435] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:66:0)
[00:21:30.435] Timer tick, asking Buildbucket for the build status
[00:21:31.094] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:31.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:67:0) after 1m0s
[00:22:31.067] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:67:0)
[00:22:31.067] Timer tick, asking Buildbucket for the build status
[00:22:32.202] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:32.202] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:68:0) after 1m0s
[00:23:32.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:68:0)
[00:23:32.221] Timer tick, asking Buildbucket for the build status
[00:23:32.334] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:32.335] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:69:0) after 1m0s
[00:24:32.334] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:69:0)
[00:24:32.334] Timer tick, asking Buildbucket for the build status
[00:24:33.007] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:33.007] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:70:0) after 1m0s
[00:25:32.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:70:0)
[00:25:32.986] Timer tick, asking Buildbucket for the build status
[00:25:33.172] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:33.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:71:0) after 1m0s
[00:26:33.171] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:71:0)
[00:26:33.171] Timer tick, asking Buildbucket for the build status
[00:26:33.548] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:33.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:72:0) after 1m0s
[00:27:33.569] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:72:0)
[00:27:33.569] Timer tick, asking Buildbucket for the build status
[00:27:34.374] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:34.374] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:73:0) after 1m0s
[00:28:34.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:73:0)
[00:28:34.394] Timer tick, asking Buildbucket for the build status
[00:28:34.538] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:34.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:74:0) after 1m0s
[00:29:34.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:74:0)
[00:29:34.536] Timer tick, asking Buildbucket for the build status
[00:29:34.952] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:34.952] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:75:0) after 1m0s
[00:30:34.933] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:75:0)
[00:30:34.933] Timer tick, asking Buildbucket for the build status
[00:30:35.114] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:35.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:76:0) after 1m0s
[00:31:35.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:76:0)
[00:31:35.139] Timer tick, asking Buildbucket for the build status
[00:31:35.516] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:35.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:77:0) after 1m0s
[00:32:35.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:77:0)
[00:32:35.524] Timer tick, asking Buildbucket for the build status
[00:32:35.640] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:35.640] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:78:0) after 1m0s
[00:33:35.617] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:78:0)
[00:33:35.617] Timer tick, asking Buildbucket for the build status
[00:33:35.984] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:35.984] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:79:0) after 1m0s
[00:34:35.999] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:79:0)
[00:34:35.999] Timer tick, asking Buildbucket for the build status
[00:34:36.874] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:36.874] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:80:0) after 1m0s
[00:35:36.897] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:80:0)
[00:35:36.897] Timer tick, asking Buildbucket for the build status
[00:35:37.497] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:37.497] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:81:0) after 1m0s
[00:36:37.543] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:81:0)
[00:36:37.543] Timer tick, asking Buildbucket for the build status
[00:36:37.711] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:37.711] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:82:0) after 1m0s
[00:37:37.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:82:0)
[00:37:37.726] Timer tick, asking Buildbucket for the build status
[00:37:38.170] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:38.171] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:83:0) after 1m0s
[00:38:38.192] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:83:0)
[00:38:38.192] Timer tick, asking Buildbucket for the build status
[00:38:38.447] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:38.447] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:84:0) after 1m0s
[00:39:38.486] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:84:0)
[00:39:38.486] Timer tick, asking Buildbucket for the build status
[00:39:38.587] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:38.587] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:85:0) after 1m0s
[00:40:38.610] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:85:0)
[00:40:38.610] Timer tick, asking Buildbucket for the build status
[00:40:39.068] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:39.068] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:86:0) after 1m0s
[00:41:39.097] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:86:0)
[00:41:39.097] Timer tick, asking Buildbucket for the build status
[00:41:39.200] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:39.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:87:0) after 1m0s
[00:42:39.246] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:87:0)
[00:42:39.246] Timer tick, asking Buildbucket for the build status
[00:42:40.065] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:40.065] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:88:0) after 1m0s
[00:43:40.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:88:0)
[00:43:40.070] Timer tick, asking Buildbucket for the build status
[00:43:40.426] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:40.426] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:89:0) after 1m0s
[00:44:40.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:89:0)
[00:44:40.421] Timer tick, asking Buildbucket for the build status
[00:44:40.567] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:40.567] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:90:0) after 1m0s
[00:45:40.566] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:90:0)
[00:45:40.566] Timer tick, asking Buildbucket for the build status
[00:45:40.817] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:40.817] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:91:0) after 1m0s
[00:46:40.882] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:91:0)
[00:46:40.882] Timer tick, asking Buildbucket for the build status
[00:46:41.195] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:41.195] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:92:0) after 1m0s
[00:47:41.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:92:0)
[00:47:41.224] Timer tick, asking Buildbucket for the build status
[00:47:41.378] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:41.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:93:0) after 1m0s
[00:48:41.404] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:93:0)
[00:48:41.404] Timer tick, asking Buildbucket for the build status
[00:48:42.309] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:42.309] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:94:0) after 1m0s
[00:49:42.333] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:94:0)
[00:49:42.333] Timer tick, asking Buildbucket for the build status
[00:49:42.439] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:42.439] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:95:0) after 1m0s
[00:50:42.466] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:95:0)
[00:50:42.466] Timer tick, asking Buildbucket for the build status
[00:50:42.672] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:42.672] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:96:0) after 1m0s
[00:51:42.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:96:0)
[00:51:42.717] Timer tick, asking Buildbucket for the build status
[00:51:42.813] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:42.813] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:97:0) after 1m0s
[00:52:42.839] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:97:0)
[00:52:42.839] Timer tick, asking Buildbucket for the build status
[00:52:43.089] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:43.089] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:98:0) after 1m0s
[00:53:43.068] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:98:0)
[00:53:43.068] Timer tick, asking Buildbucket for the build status
[00:53:43.176] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:43.176] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:99:0) after 1m0s
[00:54:43.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:99:0)
[00:54:43.184] Timer tick, asking Buildbucket for the build status
[00:54:44.087] Build 8918334750080198848: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:44.087] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9083764812133495168:100:0) after 1m0s
[00:55:14.485] Received PubSub notification, asking Buildbucket for the build status
[00:55:14.821] Build 8918334750080198848: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[00:55:14.821] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1553216113739596",
"created_by": "project:chromium",
"created_ts": "1553210225943355",
"failure_reason": "BUILD_FAILURE",
"id": "8918334750080198848",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"2 tests failed: ['non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/swarming/w/ir/cache\", \"temp_dir\": \"/b/swarming/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"swarm1567-c4\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 11488, \"got_angle_revision\": \"3e8a8d5b8567d08855d500c85bcf7cdcc4653f9a\", \"got_dawn_revision\": \"e105f962cf0020ee96a5a14a881c0de4ad46706c\", \"got_nacl_revision\": \"7d24fb15d81f95ed30dde0c04b68584749709657\", \"got_revision\": \"3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b\", \"got_revision_cp\": \"refs/heads/master@{#643165}\", \"got_swarming_client_revision\": \"aa60736aded9fc32a0e21a81f5fc51f6009d01f3\", \"got_v8_revision\": \"4f6ba4c1da8c69d00ba2379d6204adb14bfe68e5\", \"got_v8_revision_cp\": \"refs/heads/7.5.84@{#4}\", \"got_webrtc_revision\": \"741daaf0393c4ba42c1bfc9c88907aaa09349987\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#27227}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mastername\": \"chromium.chromiumos\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b\", \"swarm_hashes\": {\"accessibility_unittests\": \"72957e8bdb628def28ce038adbee28c925ea46fc\", \"angle_unittests\": \"2158193525c97101754efd41de60db590517e908\", \"app_list_unittests\": \"cd40276a09122e7476db8f3a590d3d160c5d181e\", \"app_shell_unittests\": \"2102852f5e515076b796250c29832447af805dfc\", \"ash_unittests\": \"0492a350009482af904ce4b4f4fccb31fcbee6d7\", \"aura_unittests\": \"ac46b12963b5704ef4f4805214573651c0966581\", \"base_unittests\": \"c8a6c7cfec8abc097328613e0025de4e1ed334d5\", \"blink_common_unittests\": \"539d86c89a21f04c8db63215485723ff3b475932\", \"blink_fuzzer_unittests\": \"a0b3af8e0c95f6b91d3632853c6b3296254d9232\", \"blink_heap_unittests\": \"c53867213d8843bc910acdeae968db588352bc6d\", \"blink_platform_unittests\": \"32f6a807bc220caf12d7a858fbcab148e6bbc053\", \"blink_unittests\": \"e92fca2d2558e6421fefe1897f05f74f17987d4a\", \"boringssl_crypto_tests\": \"143dd4a70d9119a92bd0be51c5e28fcbf5b63b32\", \"boringssl_ssl_tests\": \"764d0af444c58c52429ea1f96778a1a6ee113188\", \"browser_tests\": \"bfaf334e1f40b7b017fb963eff6fcc25e88b9727\", \"cacheinvalidation_unittests\": \"abe9d769150059c71fee395f86b84098839bbae0\", \"capture_unittests\": \"9053ab50655fdfed9e3296e770c4dcc12a84139f\", \"cast_unittests\": \"7b2cb735c52539da58d99a17589796a603b33b09\", \"cc_unittests\": \"833c44be29ddde2ff8b152cba828e2c195bcb5c5\", \"chrome_app_unittests\": \"a7918831ee0e98fd89854b292fc026fcd1c47c59\", \"chromedriver_unittests\": \"7454fbbf5eb914a7e94d75843fd270c70547f19b\", \"chromeos_components_unittests\": \"5b89caa0690107a6eda949f136173b1cdd9aa575\", \"chromeos_unittests\": \"d655c576860bab300ad30d167fb782fb91eb641e\", \"components_browsertests\": \"9bc2a9e2a9b3ad04d57edd2d223a607400befc5f\", \"components_unittests\": \"e1e0f09b1dce0c7f5ecb8220762566d820237fc4\", \"compositor_unittests\": \"baa426b9cf55da0b3b27559db06452e03be45ede\", \"content_browsertests\": \"146daf41ac05401183b758720af7c5b8a4f54d3d\", \"content_unittests\": \"600ee157bd7b39a99af470ab11c449ac49d3cc41\", \"crypto_unittests\": \"8379f6d62c1734b1b6b2a268263d487a4504e7de\", \"dbus_unittests\": \"1bcd680eacf613fa55b97fef1d103e1d211090bb\", \"device_unittests\": \"aa6f7fa17af9cd778650e425768da5bb3d3bc053\", \"display_unittests\": \"a7d829d0ac5e3ba579ac3eafa07aba57aa137c6a\", \"events_unittests\": \"fefb1cef3a843f16cbd761e0f1055ded340cf48e\", \"exo_unittests\": \"05cf251d57706c70372219a0ce96108cae5bd03b\", \"extensions_browsertests\": \"4ccb14f7ec3dccd6fab77baf91a437cdaa7ff2d5\", \"extensions_unittests\": \"559168a7bc9893aabbc4be2e5435c4e270e2becf\", \"filesystem_service_unittests\": \"3490c5a1c3b0b20b1cabf1ba3c1396e8f36798e8\", \"gcm_unit_tests\": \"1fc77f6418a219301733a033518379e6be64a628\", \"gfx_unittests\": \"db97910b3ab2f4c7884b85c360b72f37e1df632b\", \"gin_unittests\": \"cb803df363c00c9c1c037e4216e4c5b62746f2e6\", \"gl_unittests_ozone\": \"709d10db60ebe39e901d4c1bbd4060bbb6cefc00\", \"google_apis_unittests\": \"34cc6fd075e3a446c4145188d11734330f747cba\", \"gpu_unittests\": \"9c6e517d413cad65f01f63466b7dd7c79a6aab99\", \"interactive_ui_tests\": \"085e2891245d93a8c8219d9313eba0df59fa00de\", \"ipc_tests\": \"1891194f6b16f099a5d25d32c9e604f785f1bd38\", \"jingle_unittests\": \"0272ea9d3db83a740ea2859cfa5527341fc35196\", \"keyboard_unittests\": \"9feed6dde9d66c8c3ad0c53ecb87a62c9161a0ee\", \"latency_unittests\": \"4d54b337ac59c5cc8a910570a2d49a9605700804\", \"leveldb_service_unittests\": \"eb3b1ab351ff0d46ef7416260af23f91d3aee28c\", \"libjingle_xmpp_unittests\": \"0932a8ab8e251a5f19fc0294e524fb71763b868d\", \"media_blink_unittests\": \"b23bbb250c81a9b5d2de82081fddab43801955a5\", \"media_service_unittests\": \"92df7bd0c5c8dcb4b1a605d4a2b2f6e7bec2811a\", \"media_unittests\": \"f5d4f87bb670d7789b70a65d3f5737e27551de9a\", \"message_center_unittests\": \"2bffbc19362f9161d262d21aa615e20eee19b1c6\", \"midi_unittests\": \"d48d229ad173f6d607b5117544d47b2d50681df9\", \"mojo_core_unittests\": \"1a0e122fe2be4dccaf215413216febbdd4f7fc4b\", \"mojo_unittests\": \"a156bf55b33d30148d99bb441d62130116e6895d\", \"nacl_helper_nonsfi_unittests\": \"017cb07883a805c2da74f1fa595b5b518e5b7616\", \"nacl_loader_unittests\": \"5b0757d10ad074f62bcfe4a40c26701cd9e669b0\", \"native_theme_unittests\": \"5ceebb454b870b5176161981f52ed0880de92bd2\", \"net_unittests\": \"9105ef76b3de6d88e933e0246d84343e32e5de39\", \"ozone_gl_unittests\": \"318b3dfa48f8d35eff138a84490f8ef3163ebfaa\", \"ozone_unittests\": \"79ba2ae3789dc1032ad8b529e29641ee3c30911f\", \"ozone_x11_unittests\": \"ba68447605b2741d0d9995cdc16284230f876252\", \"pdf_unittests\": \"742459f703dfa01bb88a531b9fd7bbb58257dafa\", \"perfetto_unittests\": \"cdaf2e9a8fbee7702da64daf7d81ae2f6657e910\", \"ppapi_unittests\": \"09c202e6a2b12d516237d96a6e5be19131888b31\", \"printing_unittests\": \"14a8a779c896c6455a48d97268a9663346a7a1fe\", \"remoting_unittests\": \"3f08beb43c57ebb2dfa33c731c077ddb86a1bfa1\", \"sandbox_linux_unittests\": \"603a02028ed5daf3bda29b75faadeff202ae3191\", \"service_manager_unittests\": \"54b7ba504118009a12f8230c91c78e0e7f14d43c\", \"services_unittests\": \"328a5eb06f37789478b7fcf0da3f088cfd7626ec\", \"shell_dialogs_unittests\": \"d5041a29c36f4dae8f53478dba1d6482fab49492\", \"skia_unittests\": \"653e866a707fd44a006e5bd9062dd92784b84316\", \"snapshot_unittests\": \"bbe2d6a4dd51052844d486b7ec35e2213ecaf77a\", \"sql_unittests\": \"1279b46c1374669c1520a904e785cc14240cf0d6\", \"storage_unittests\": \"6781141d01e4958a37b076a099aa48910e2ecef9\", \"sync_integration_tests\": \"258cba0f8b1ba5156ef25c598b3143ab32d21f18\", \"traffic_annotation_auditor_unittests\": \"7ba0e79894a57346890a645ec1f3693925889dff\", \"ui_base_unittests\": \"4a5cb43467d7767c5c1c7f707d03a77a0c9f0230\", \"ui_chromeos_unittests\": \"fec00a156b672b601dc1bcb74d0b5eb34449bdca\", \"ui_touch_selection_unittests\": \"4fb2130baf9f867e2b6f694b322b3625ef11a0b8\", \"unit_tests\": \"830b0d5cb2978b8c2e53f72cbffb06db52f56f21\", \"url_unittests\": \"40d7ae5f07c7316ff9c750ae59a2c1e5707f9712\", \"usage_time_limit_unittests\": \"59ce985a531c84746be24e32fea94160ddc1016a\", \"views_unittests\": \"40a27b25b4cad059f1bea9dcaa7bae44367e4ac4\", \"viz_unittests\": \"0292d1a244dbfbe1678a7294fb15e4840abbbd88\", \"wm_unittests\": \"8534e1b847231453ffb13484018ba480708dd975\", \"wtf_unittests\": \"b57b376f54bed045410c401bd6277dd117e4e5db\"}, \"swarm_hashes_refs/heads/master(at){#643165}_without_patch\": {\"accessibility_unittests\": \"72957e8bdb628def28ce038adbee28c925ea46fc\", \"angle_unittests\": \"2158193525c97101754efd41de60db590517e908\", \"app_list_unittests\": \"cd40276a09122e7476db8f3a590d3d160c5d181e\", \"app_shell_unittests\": \"2102852f5e515076b796250c29832447af805dfc\", \"ash_unittests\": \"0492a350009482af904ce4b4f4fccb31fcbee6d7\", \"aura_unittests\": \"ac46b12963b5704ef4f4805214573651c0966581\", \"base_unittests\": \"c8a6c7cfec8abc097328613e0025de4e1ed334d5\", \"blink_common_unittests\": \"539d86c89a21f04c8db63215485723ff3b475932\", \"blink_fuzzer_unittests\": \"a0b3af8e0c95f6b91d3632853c6b3296254d9232\", \"blink_heap_unittests\": \"c53867213d8843bc910acdeae968db588352bc6d\", \"blink_platform_unittests\": \"32f6a807bc220caf12d7a858fbcab148e6bbc053\", \"blink_unittests\": \"e92fca2d2558e6421fefe1897f05f74f17987d4a\", \"boringssl_crypto_tests\": \"143dd4a70d9119a92bd0be51c5e28fcbf5b63b32\", \"boringssl_ssl_tests\": \"764d0af444c58c52429ea1f96778a1a6ee113188\", \"browser_tests\": \"bfaf334e1f40b7b017fb963eff6fcc25e88b9727\", \"cacheinvalidation_unittests\": \"abe9d769150059c71fee395f86b84098839bbae0\", \"capture_unittests\": \"9053ab50655fdfed9e3296e770c4dcc12a84139f\", \"cast_unittests\": \"7b2cb735c52539da58d99a17589796a603b33b09\", \"cc_unittests\": \"833c44be29ddde2ff8b152cba828e2c195bcb5c5\", \"chrome_app_unittests\": \"a7918831ee0e98fd89854b292fc026fcd1c47c59\", \"chromedriver_unittests\": \"7454fbbf5eb914a7e94d75843fd270c70547f19b\", \"chromeos_components_unittests\": \"5b89caa0690107a6eda949f136173b1cdd9aa575\", \"chromeos_unittests\": \"d655c576860bab300ad30d167fb782fb91eb641e\", \"components_browsertests\": \"9bc2a9e2a9b3ad04d57edd2d223a607400befc5f\", \"components_unittests\": \"e1e0f09b1dce0c7f5ecb8220762566d820237fc4\", \"compositor_unittests\": \"baa426b9cf55da0b3b27559db06452e03be45ede\", \"content_browsertests\": \"146daf41ac05401183b758720af7c5b8a4f54d3d\", \"content_unittests\": \"600ee157bd7b39a99af470ab11c449ac49d3cc41\", \"crypto_unittests\": \"8379f6d62c1734b1b6b2a268263d487a4504e7de\", \"dbus_unittests\": \"1bcd680eacf613fa55b97fef1d103e1d211090bb\", \"device_unittests\": \"aa6f7fa17af9cd778650e425768da5bb3d3bc053\", \"display_unittests\": \"a7d829d0ac5e3ba579ac3eafa07aba57aa137c6a\", \"events_unittests\": \"fefb1cef3a843f16cbd761e0f1055ded340cf48e\", \"exo_unittests\": \"05cf251d57706c70372219a0ce96108cae5bd03b\", \"extensions_browsertests\": \"4ccb14f7ec3dccd6fab77baf91a437cdaa7ff2d5\", \"extensions_unittests\": \"559168a7bc9893aabbc4be2e5435c4e270e2becf\", \"filesystem_service_unittests\": \"3490c5a1c3b0b20b1cabf1ba3c1396e8f36798e8\", \"gcm_unit_tests\": \"1fc77f6418a219301733a033518379e6be64a628\", \"gfx_unittests\": \"db97910b3ab2f4c7884b85c360b72f37e1df632b\", \"gin_unittests\": \"cb803df363c00c9c1c037e4216e4c5b62746f2e6\", \"gl_unittests_ozone\": \"709d10db60ebe39e901d4c1bbd4060bbb6cefc00\", \"google_apis_unittests\": \"34cc6fd075e3a446c4145188d11734330f747cba\", \"gpu_unittests\": \"9c6e517d413cad65f01f63466b7dd7c79a6aab99\", \"interactive_ui_tests\": \"085e2891245d93a8c8219d9313eba0df59fa00de\", \"ipc_tests\": \"1891194f6b16f099a5d25d32c9e604f785f1bd38\", \"jingle_unittests\": \"0272ea9d3db83a740ea2859cfa5527341fc35196\", \"keyboard_unittests\": \"9feed6dde9d66c8c3ad0c53ecb87a62c9161a0ee\", \"latency_unittests\": \"4d54b337ac59c5cc8a910570a2d49a9605700804\", \"leveldb_service_unittests\": \"eb3b1ab351ff0d46ef7416260af23f91d3aee28c\", \"libjingle_xmpp_unittests\": \"0932a8ab8e251a5f19fc0294e524fb71763b868d\", \"media_blink_unittests\": \"b23bbb250c81a9b5d2de82081fddab43801955a5\", \"media_service_unittests\": \"92df7bd0c5c8dcb4b1a605d4a2b2f6e7bec2811a\", \"media_unittests\": \"f5d4f87bb670d7789b70a65d3f5737e27551de9a\", \"message_center_unittests\": \"2bffbc19362f9161d262d21aa615e20eee19b1c6\", \"midi_unittests\": \"d48d229ad173f6d607b5117544d47b2d50681df9\", \"mojo_core_unittests\": \"1a0e122fe2be4dccaf215413216febbdd4f7fc4b\", \"mojo_unittests\": \"a156bf55b33d30148d99bb441d62130116e6895d\", \"nacl_helper_nonsfi_unittests\": \"017cb07883a805c2da74f1fa595b5b518e5b7616\", \"nacl_loader_unittests\": \"5b0757d10ad074f62bcfe4a40c26701cd9e669b0\", \"native_theme_unittests\": \"5ceebb454b870b5176161981f52ed0880de92bd2\", \"net_unittests\": \"9105ef76b3de6d88e933e0246d84343e32e5de39\", \"ozone_gl_unittests\": \"318b3dfa48f8d35eff138a84490f8ef3163ebfaa\", \"ozone_unittests\": \"79ba2ae3789dc1032ad8b529e29641ee3c30911f\", \"ozone_x11_unittests\": \"ba68447605b2741d0d9995cdc16284230f876252\", \"pdf_unittests\": \"742459f703dfa01bb88a531b9fd7bbb58257dafa\", \"perfetto_unittests\": \"cdaf2e9a8fbee7702da64daf7d81ae2f6657e910\", \"ppapi_unittests\": \"09c202e6a2b12d516237d96a6e5be19131888b31\", \"printing_unittests\": \"14a8a779c896c6455a48d97268a9663346a7a1fe\", \"remoting_unittests\": \"3f08beb43c57ebb2dfa33c731c077ddb86a1bfa1\", \"sandbox_linux_unittests\": \"603a02028ed5daf3bda29b75faadeff202ae3191\", \"service_manager_unittests\": \"54b7ba504118009a12f8230c91c78e0e7f14d43c\", \"services_unittests\": \"328a5eb06f37789478b7fcf0da3f088cfd7626ec\", \"shell_dialogs_unittests\": \"d5041a29c36f4dae8f53478dba1d6482fab49492\", \"skia_unittests\": \"653e866a707fd44a006e5bd9062dd92784b84316\", \"snapshot_unittests\": \"bbe2d6a4dd51052844d486b7ec35e2213ecaf77a\", \"sql_unittests\": \"1279b46c1374669c1520a904e785cc14240cf0d6\", \"storage_unittests\": \"6781141d01e4958a37b076a099aa48910e2ecef9\", \"sync_integration_tests\": \"258cba0f8b1ba5156ef25c598b3143ab32d21f18\", \"traffic_annotation_auditor_unittests\": \"7ba0e79894a57346890a645ec1f3693925889dff\", \"ui_base_unittests\": \"4a5cb43467d7767c5c1c7f707d03a77a0c9f0230\", \"ui_chromeos_unittests\": \"fec00a156b672b601dc1bcb74d0b5eb34449bdca\", \"ui_touch_selection_unittests\": \"4fb2130baf9f867e2b6f694b322b3625ef11a0b8\", \"unit_tests\": \"830b0d5cb2978b8c2e53f72cbffb06db52f56f21\", \"url_unittests\": \"40d7ae5f07c7316ff9c750ae59a2c1e5707f9712\", \"usage_time_limit_unittests\": \"59ce985a531c84746be24e32fea94160ddc1016a\", \"views_unittests\": \"40a27b25b4cad059f1bea9dcaa7bae44367e4ac4\", \"viz_unittests\": \"0292d1a244dbfbe1678a7294fb15e4840abbbd88\", \"wm_unittests\": \"8534e1b847231453ffb13484018ba480708dd975\", \"wtf_unittests\": \"b57b376f54bed045410c401bd6277dd117e4e5db\"}}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"linux-chromeos-dbg\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"swarm1567-c4\"], \"image\": [\"chrome-trusty-18042300-b7223b463e3\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.6\"], \"server_version\": [\"4213-ad7b457\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}, \"ui\": {\"info\": \"2 tests failed: ['non_network_service_browser_tests', 'non_network_service_interactive_ui_tests']\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1553210339210045",
"status": "COMPLETED",
"status_changed_ts": "1553210339644216",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/11488",
"builder:linux-chromeos-dbg",
"buildset:commit/git/3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3513f7c9dbb841af3e14f5a8a5ddaa2e2ceb559b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9083764812133495168",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8918334750080198848/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:43bb5cbf381e1c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1553216114197143",
"url": "https://ci.chromium.org/b/8918334750080198848",
"utcnow_ts": "1553216114812316"
}
}
[00:55:14.821] Invocation finished in 1h38m10.220045739s with status FAILED