[05:56:54.178] New invocation is queued and will start shortly
[05:56:55.259] Starting the invocation (attempt 1)
[05:56:55.295] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:56:55.296] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:56:55.296] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022133720103394016",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4278a7641b47ba40c9906e364e8d3dda9b5bb3d1\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9022133720103394016",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/4278a7641b47ba40c9906e364e8d3dda9b5bb3d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4278a7641b47ba40c9906e364e8d3dda9b5bb3d1",
"gitiles_ref:refs/heads/master"
]
}
[05:56:56.219] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611986215416616",
"id": "8856703658142691280",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4278a7641b47ba40c9906e364e8d3dda9b5bb3d1\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611986215989363",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22408",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4278a7641b47ba40c9906e364e8d3dda9b5bb3d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4278a7641b47ba40c9906e364e8d3dda9b5bb3d1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022133720103394016",
"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/8856703658142691280/+/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:",
"user_agent:luci-scheduler"
],
"updated_ts": "1611986215989477",
"url": "https://ci.chromium.org/b/8856703658142691280",
"utcnow_ts": "1611986216203755"
}
}
[05:56:56.219] Task URL: https://ci.chromium.org/b/8856703658142691280
[05:56:56.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:2:0) after 1m0s
[05:57:56.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:2:0)
[05:57:56.267] Timer tick, asking Buildbucket for the build status
[05:57:56.375] Build 8856703658142691280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:57:56.375] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:3:0) after 1m0s
[05:58:56.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:3:0)
[05:58:56.403] Timer tick, asking Buildbucket for the build status
[05:58:56.562] Build 8856703658142691280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:58:56.562] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:4:0) after 1m0s
[05:59:56.640] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:4:0)
[05:59:56.640] Timer tick, asking Buildbucket for the build status
[05:59:56.936] Build 8856703658142691280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[05:59:56.936] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:5:0) after 1m0s
[06:00:56.989] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:5:0)
[06:00:56.989] Timer tick, asking Buildbucket for the build status
[06:00:57.070] Build 8856703658142691280: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:00:57.070] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:6:0) after 1m0s
[06:01:23.338] Received PubSub notification, asking Buildbucket for the build status
[06:01:23.593] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:57.105] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:6:0)
[06:01:57.105] Timer tick, asking Buildbucket for the build status
[06:01:57.181] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:57.181] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:8:0) after 1m0s
[06:02:57.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:8:0)
[06:02:57.200] Timer tick, asking Buildbucket for the build status
[06:02:57.429] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:57.429] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:9:0) after 1m0s
[06:03:57.456] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:9:0)
[06:03:57.456] Timer tick, asking Buildbucket for the build status
[06:03:57.799] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:57.799] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:10:0) after 1m0s
[06:04:57.820] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:10:0)
[06:04:57.820] Timer tick, asking Buildbucket for the build status
[06:04:57.880] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:57.880] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:11:0) after 1m0s
[06:05:57.901] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:11:0)
[06:05:57.901] Timer tick, asking Buildbucket for the build status
[06:05:58.142] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:58.142] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:12:0) after 1m0s
[06:06:58.139] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:12:0)
[06:06:58.139] Timer tick, asking Buildbucket for the build status
[06:06:58.528] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:58.528] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:13:0) after 1m0s
[06:07:58.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:13:0)
[06:07:58.548] Timer tick, asking Buildbucket for the build status
[06:07:58.657] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:58.657] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:14:0) after 1m0s
[06:08:58.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:14:0)
[06:08:58.675] Timer tick, asking Buildbucket for the build status
[06:08:58.786] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:58.786] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:15:0) after 1m0s
[06:09:58.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:15:0)
[06:09:58.766] Timer tick, asking Buildbucket for the build status
[06:09:58.879] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:58.879] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:16:0) after 1m0s
[06:10:58.961] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:16:0)
[06:10:58.961] Timer tick, asking Buildbucket for the build status
[06:10:59.229] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:59.229] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:17:0) after 1m0s
[06:11:59.315] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:17:0)
[06:11:59.315] Timer tick, asking Buildbucket for the build status
[06:11:59.448] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:59.448] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:18:0) after 1m0s
[06:12:59.454] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:18:0)
[06:12:59.454] Timer tick, asking Buildbucket for the build status
[06:12:59.544] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:59.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:19:0) after 1m0s
[06:13:59.578] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:19:0)
[06:13:59.579] Timer tick, asking Buildbucket for the build status
[06:13:59.722] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:59.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:20:0) after 1m0s
[06:14:59.744] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:20:0)
[06:14:59.744] Timer tick, asking Buildbucket for the build status
[06:14:59.869] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:59.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:21:0) after 1m0s
[06:15:59.889] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:21:0)
[06:15:59.889] Timer tick, asking Buildbucket for the build status
[06:15:59.953] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:59.953] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:22:0) after 1m0s
[06:16:59.967] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:22:0)
[06:16:59.967] Timer tick, asking Buildbucket for the build status
[06:17:00.172] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:00.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:23:0) after 1m0s
[06:18:00.216] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:23:0)
[06:18:00.216] Timer tick, asking Buildbucket for the build status
[06:18:00.282] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:00.282] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:24:0) after 1m0s
[06:19:00.346] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:24:0)
[06:19:00.346] Timer tick, asking Buildbucket for the build status
[06:19:00.412] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:00.412] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:25:0) after 1m0s
[06:20:00.432] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:25:0)
[06:20:00.432] Timer tick, asking Buildbucket for the build status
[06:20:00.494] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:00.494] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:26:0) after 1m0s
[06:21:00.661] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:26:0)
[06:21:00.661] Timer tick, asking Buildbucket for the build status
[06:21:00.735] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:00.735] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:27:0) after 1m0s
[06:22:00.814] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:27:0)
[06:22:00.814] Timer tick, asking Buildbucket for the build status
[06:22:00.924] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:00.924] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:28:0) after 1m0s
[06:23:00.952] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:28:0)
[06:23:00.952] Timer tick, asking Buildbucket for the build status
[06:23:01.084] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:01.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:29:0) after 1m0s
[06:24:01.152] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:29:0)
[06:24:01.152] Timer tick, asking Buildbucket for the build status
[06:24:01.227] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:01.227] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:30:0) after 1m0s
[06:25:01.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:30:0)
[06:25:01.248] Timer tick, asking Buildbucket for the build status
[06:25:01.714] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:01.714] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:31:0) after 1m0s
[06:26:01.779] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:31:0)
[06:26:01.779] Timer tick, asking Buildbucket for the build status
[06:26:01.846] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:01.846] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:32:0) after 1m0s
[06:27:01.927] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:32:0)
[06:27:01.927] Timer tick, asking Buildbucket for the build status
[06:27:02.221] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:02.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:33:0) after 1m0s
[06:28:02.245] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:33:0)
[06:28:02.245] Timer tick, asking Buildbucket for the build status
[06:28:02.423] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:02.423] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:34:0) after 1m0s
[06:29:02.510] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:34:0)
[06:29:02.510] Timer tick, asking Buildbucket for the build status
[06:29:02.764] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:02.764] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:35:0) after 1m0s
[06:30:02.828] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:35:0)
[06:30:02.828] Timer tick, asking Buildbucket for the build status
[06:30:02.928] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:02.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:36:0) after 1m0s
[06:31:02.973] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:36:0)
[06:31:02.974] Timer tick, asking Buildbucket for the build status
[06:31:03.156] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:03.156] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:37:0) after 1m0s
[06:32:03.200] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:37:0)
[06:32:03.200] Timer tick, asking Buildbucket for the build status
[06:32:03.375] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:03.376] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:38:0) after 1m0s
[06:33:03.390] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:38:0)
[06:33:03.390] Timer tick, asking Buildbucket for the build status
[06:33:03.579] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:03.579] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:39:0) after 1m0s
[06:34:03.632] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:39:0)
[06:34:03.632] Timer tick, asking Buildbucket for the build status
[06:34:03.769] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:03.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:40:0) after 1m0s
[06:35:03.842] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:40:0)
[06:35:03.842] Timer tick, asking Buildbucket for the build status
[06:35:04.028] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:04.028] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:41:0) after 1m0s
[06:36:04.086] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:41:0)
[06:36:04.086] Timer tick, asking Buildbucket for the build status
[06:36:04.201] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:04.201] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:42:0) after 1m0s
[06:37:04.255] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:42:0)
[06:37:04.255] Timer tick, asking Buildbucket for the build status
[06:37:04.517] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:04.517] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:43:0) after 1m0s
[06:38:04.527] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:43:0)
[06:38:04.527] Timer tick, asking Buildbucket for the build status
[06:38:04.925] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:04.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:44:0) after 1m0s
[06:39:04.950] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:44:0)
[06:39:04.950] Timer tick, asking Buildbucket for the build status
[06:39:05.027] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:05.027] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:45:0) after 1m0s
[06:40:05.079] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:45:0)
[06:40:05.079] Timer tick, asking Buildbucket for the build status
[06:40:05.155] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:05.155] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:46:0) after 1m0s
[06:41:05.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:46:0)
[06:41:05.224] Timer tick, asking Buildbucket for the build status
[06:41:05.291] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:05.291] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:47:0) after 1m0s
[06:42:05.403] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:47:0)
[06:42:05.404] Timer tick, asking Buildbucket for the build status
[06:42:05.511] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:05.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:48:0) after 1m0s
[06:43:05.656] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:48:0)
[06:43:05.656] Timer tick, asking Buildbucket for the build status
[06:43:05.722] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:05.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:49:0) after 1m0s
[06:44:05.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:49:0)
[06:44:05.747] Timer tick, asking Buildbucket for the build status
[06:44:06.039] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:06.039] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:50:0) after 1m0s
[06:45:06.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:50:0)
[06:45:06.110] Timer tick, asking Buildbucket for the build status
[06:45:06.214] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:06.214] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:51:0) after 1m0s
[06:46:06.407] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:51:0)
[06:46:06.407] Timer tick, asking Buildbucket for the build status
[06:46:06.895] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:06.895] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:52:0) after 1m0s
[06:47:07.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:52:0)
[06:47:07.102] Timer tick, asking Buildbucket for the build status
[06:47:07.513] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:07.513] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:53:0) after 1m0s
[06:48:07.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:53:0)
[06:48:07.581] Timer tick, asking Buildbucket for the build status
[06:48:07.860] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:07.860] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:54:0) after 1m0s
[06:49:07.955] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:54:0)
[06:49:07.955] Timer tick, asking Buildbucket for the build status
[06:49:08.119] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:08.119] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:55:0) after 1m0s
[06:50:08.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:55:0)
[06:50:08.319] Timer tick, asking Buildbucket for the build status
[06:50:08.382] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:08.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:56:0) after 1m0s
[06:51:08.589] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:56:0)
[06:51:08.589] Timer tick, asking Buildbucket for the build status
[06:51:08.703] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:08.703] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:57:0) after 1m0s
[06:52:08.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:57:0)
[06:52:08.838] Timer tick, asking Buildbucket for the build status
[06:52:09.114] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:09.114] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:58:0) after 1m0s
[06:53:09.225] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:58:0)
[06:53:09.225] Timer tick, asking Buildbucket for the build status
[06:53:09.578] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:09.578] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:59:0) after 1m0s
[06:54:09.748] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:59:0)
[06:54:09.748] Timer tick, asking Buildbucket for the build status
[06:54:10.083] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:10.083] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:60:0) after 1m0s
[06:55:10.174] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:60:0)
[06:55:10.174] Timer tick, asking Buildbucket for the build status
[06:55:10.326] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:10.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:61:0) after 1m0s
[06:56:10.378] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:61:0)
[06:56:10.378] Timer tick, asking Buildbucket for the build status
[06:56:10.667] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:10.667] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:62:0) after 1m0s
[06:57:10.916] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:62:0)
[06:57:10.916] Timer tick, asking Buildbucket for the build status
[06:57:11.228] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:11.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:63:0) after 1m0s
[06:58:11.311] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:63:0)
[06:58:11.311] Timer tick, asking Buildbucket for the build status
[06:58:11.417] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:11.417] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:64:0) after 1m0s
[06:59:11.555] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:64:0)
[06:59:11.555] Timer tick, asking Buildbucket for the build status
[06:59:11.649] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:11.649] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:65:0) after 1m0s
[07:00:11.732] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:65:0)
[07:00:11.732] Timer tick, asking Buildbucket for the build status
[07:00:11.958] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:11.958] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:66:0) after 1m0s
[07:01:12.428] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:66:0)
[07:01:12.428] Timer tick, asking Buildbucket for the build status
[07:01:12.495] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:12.495] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:67:0) after 1m0s
[07:02:12.717] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:67:0)
[07:02:12.717] Timer tick, asking Buildbucket for the build status
[07:02:12.818] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:12.818] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:68:0) after 1m0s
[07:03:12.862] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:68:0)
[07:03:12.862] Timer tick, asking Buildbucket for the build status
[07:03:12.925] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:12.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:69:0) after 1m0s
[07:04:14.215] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:69:0)
[07:04:14.215] Timer tick, asking Buildbucket for the build status
[07:04:14.280] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:14.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:70:0) after 1m0s
[07:05:14.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:70:0)
[07:05:14.441] Timer tick, asking Buildbucket for the build status
[07:05:14.638] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:14.639] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:71:0) after 1m0s
[07:06:14.718] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:71:0)
[07:06:14.718] Timer tick, asking Buildbucket for the build status
[07:06:15.892] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:15.892] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:72:0) after 1m0s
[07:07:15.951] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:72:0)
[07:07:15.951] Timer tick, asking Buildbucket for the build status
[07:07:16.064] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:16.064] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:73:0) after 1m0s
[07:08:16.153] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:73:0)
[07:08:16.153] Timer tick, asking Buildbucket for the build status
[07:08:16.326] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:16.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:74:0) after 1m0s
[07:09:16.410] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:74:0)
[07:09:16.410] Timer tick, asking Buildbucket for the build status
[07:09:16.536] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:16.536] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:75:0) after 1m0s
[07:10:16.784] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:75:0)
[07:10:16.784] Timer tick, asking Buildbucket for the build status
[07:10:17.502] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:17.502] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:76:0) after 1m0s
[07:11:17.581] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:76:0)
[07:11:17.581] Timer tick, asking Buildbucket for the build status
[07:11:17.812] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:17.812] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:77:0) after 1m0s
[07:12:17.840] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:77:0)
[07:12:17.840] Timer tick, asking Buildbucket for the build status
[07:12:18.031] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:18.031] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:78:0) after 1m0s
[07:13:18.151] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:78:0)
[07:13:18.151] Timer tick, asking Buildbucket for the build status
[07:13:18.270] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:18.270] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:79:0) after 1m0s
[07:14:18.322] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:79:0)
[07:14:18.322] Timer tick, asking Buildbucket for the build status
[07:14:18.387] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:18.387] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:80:0) after 1m0s
[07:15:18.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:80:0)
[07:15:18.365] Timer tick, asking Buildbucket for the build status
[07:15:18.472] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:18.472] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:81:0) after 1m0s
[07:16:18.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:81:0)
[07:16:18.621] Timer tick, asking Buildbucket for the build status
[07:16:19.074] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:19.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:82:0) after 1m0s
[07:17:19.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:82:0)
[07:17:19.102] Timer tick, asking Buildbucket for the build status
[07:17:19.222] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:19.222] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:83:0) after 1m0s
[07:18:19.365] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:83:0)
[07:18:19.365] Timer tick, asking Buildbucket for the build status
[07:18:19.453] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:19.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:84:0) after 1m0s
[07:19:19.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:84:0)
[07:19:19.621] Timer tick, asking Buildbucket for the build status
[07:19:19.724] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:19.724] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:85:0) after 1m0s
[07:20:19.838] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:85:0)
[07:20:19.838] Timer tick, asking Buildbucket for the build status
[07:20:20.516] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:20.516] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:86:0) after 1m0s
[07:21:20.655] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:86:0)
[07:21:20.655] Timer tick, asking Buildbucket for the build status
[07:21:20.736] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:20.736] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:87:0) after 1m0s
[07:22:20.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:87:0)
[07:22:20.826] Timer tick, asking Buildbucket for the build status
[07:22:20.956] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:20.956] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:88:0) after 1m0s
[07:23:20.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:88:0)
[07:23:20.998] Timer tick, asking Buildbucket for the build status
[07:23:21.115] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:21.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:89:0) after 1m0s
[07:24:21.117] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:89:0)
[07:24:21.117] Timer tick, asking Buildbucket for the build status
[07:24:21.428] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:21.428] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:90:0) after 1m0s
[07:25:21.453] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:90:0)
[07:25:21.453] Timer tick, asking Buildbucket for the build status
[07:25:21.647] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:21.647] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:91:0) after 1m0s
[07:26:21.685] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:91:0)
[07:26:21.685] Timer tick, asking Buildbucket for the build status
[07:26:21.757] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:21.758] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:92:0) after 1m0s
[07:27:21.851] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:92:0)
[07:27:21.851] Timer tick, asking Buildbucket for the build status
[07:27:22.585] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:22.585] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:93:0) after 1m0s
[07:28:22.663] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:93:0)
[07:28:22.663] Timer tick, asking Buildbucket for the build status
[07:28:22.814] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:22.814] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:94:0) after 1m0s
[07:29:22.826] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:94:0)
[07:29:22.826] Timer tick, asking Buildbucket for the build status
[07:29:22.950] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:22.950] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:95:0) after 1m0s
[07:30:23.013] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:95:0)
[07:30:23.013] Timer tick, asking Buildbucket for the build status
[07:30:23.118] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:23.118] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:96:0) after 1m0s
[07:31:23.184] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:96:0)
[07:31:23.184] Timer tick, asking Buildbucket for the build status
[07:31:23.278] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:23.278] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:97:0) after 1m0s
[07:32:23.330] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:97:0)
[07:32:23.330] Timer tick, asking Buildbucket for the build status
[07:32:23.847] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:23.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:98:0) after 1m0s
[07:33:23.865] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:98:0)
[07:33:23.866] Timer tick, asking Buildbucket for the build status
[07:33:23.943] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:23.943] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:99:0) after 1m0s
[07:34:24.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:99:0)
[07:34:24.004] Timer tick, asking Buildbucket for the build status
[07:34:24.221] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:24.221] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:100:0) after 1m0s
[07:35:25.548] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:100:0)
[07:35:25.548] Timer tick, asking Buildbucket for the build status
[07:35:25.622] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:25.622] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:101:0) after 1m0s
[07:36:25.785] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:101:0)
[07:36:25.785] Timer tick, asking Buildbucket for the build status
[07:36:25.866] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:25.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:102:0) after 1m0s
[07:37:26.159] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:102:0)
[07:37:26.159] Timer tick, asking Buildbucket for the build status
[07:37:26.534] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:26.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:103:0) after 1m0s
[07:38:26.739] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:103:0)
[07:38:26.739] Timer tick, asking Buildbucket for the build status
[07:38:26.837] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:26.837] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:104:0) after 1m0s
[07:39:27.038] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:104:0)
[07:39:27.038] Timer tick, asking Buildbucket for the build status
[07:39:27.104] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:27.104] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:105:0) after 1m0s
[07:40:27.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:105:0)
[07:40:27.214] Timer tick, asking Buildbucket for the build status
[07:40:27.721] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:27.722] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:106:0) after 1m0s
[07:41:27.821] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:106:0)
[07:41:27.821] Timer tick, asking Buildbucket for the build status
[07:41:28.069] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:28.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:107:0) after 1m0s
[07:42:28.093] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:107:0)
[07:42:28.093] Timer tick, asking Buildbucket for the build status
[07:42:28.395] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:28.395] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:108:0) after 1m0s
[07:43:28.430] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:108:0)
[07:43:28.430] Timer tick, asking Buildbucket for the build status
[07:43:28.524] Build 8856703658142691280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:28.524] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9022133720103394016:109:0) after 1m0s
[07:44:04.830] Received PubSub notification, asking Buildbucket for the build status
[07:44:05.338] Build 8856703658142691280: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:44:05.338] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611992643671348",
"created_by": "project:chromium",
"created_ts": "1611986215416616",
"id": "8856703658142691280",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4278a7641b47ba40c9906e364e8d3dda9b5bb3d1\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"4278a7641b47ba40c9906e364e8d3dda9b5bb3d1\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-xenial-us-east1-d-8-61-5x80\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 22408, \"got_angle_revision\": \"74f7be6d1fd020a432dee63222fa090e4d8df651\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"4278a7641b47ba40c9906e364e8d3dda9b5bb3d1\", \"got_revision_cp\": \"refs/heads/master@{#848870}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"f9a6148e0498abdbe74eb6edf6aac78667d29499\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33109}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.chromiumos:linux-chromeos-dbg\", \"tryserver.chromium.chromiumos:linux-chromeos-compile-dbg\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4278a7641b47ba40c9906e364e8d3dda9b5bb3d1\", \"swarm_hashes_refs/heads/master(at){#848870}_without_patch\": {\"absl_hardening_tests\": \"ebf8df651c660dfcfaaaa5413b6b5066df304bd2\", \"accessibility_unittests\": \"e4011ba987fbe403a4b994b02a3e4306a1f45efa\", \"angle_unittests\": \"ffb5f108145a7bc3394abcbc64a90b7f26567453\", \"app_list_unittests\": \"f54ef685e7a118836a611231c54f4979203351dc\", \"app_shell_unittests\": \"fcef73ccfe96cc9ebde15ea21ea420d50a9e28f8\", \"ash_unittests\": \"3f15995df719d8fd9fae6385c11a96baba93cf2c\", \"aura_unittests\": \"c81fffd345024061c1e157dbeb1b876f1e24a699\", \"base_unittests\": \"567d9a0cec1a5626251a7f597b695316fd0a9c73\", \"base_util_unittests\": \"2a3029ff80f83554f165b76dbe5134b47627ffd9\", \"blink_common_unittests\": \"4f4a3f5b3d98c2ccf483f151dd09782777a6671c\", \"blink_fuzzer_unittests\": \"d65dd94e3afa1b93da07ba7103cd9a6f7207a142\", \"blink_heap_unittests\": \"42dbf2a423f9721d85c06f9e1d987b64f6f1b578\", \"blink_platform_unittests\": \"3c1784f89eb29f0a130ebffa841e76aef9cf8c8d\", \"blink_unittests\": \"a7846b07bb5d34bceab8e8393c6732a4f57e7a82\", \"boringssl_crypto_tests\": \"14896789cc439f669fa943c82ecc31d09e483be4\", \"boringssl_ssl_tests\": \"eefe7cd34913b9bd3b6c39262ca860e9ad718862\", \"browser_tests\": \"5418c25b58d0f8ebe018d3d22d9befd74ad7df2e\", \"capture_unittests\": \"01d1b466e1d4d7d3be9429a79a80d2c81c54f184\", \"cast_unittests\": \"8da12d21f7987a24412897114e2d07f5e8a66ec3\", \"cc_unittests\": \"21cdda2afa0241f1c2cedf2d355f2f9489862087\", \"chrome_app_unittests\": \"b741550332d52afc5e4e30a4ed4830f0694cff78\", \"chromedriver_unittests\": \"94c367f1e9aa269f1e75a51516c142ddc67d70b0\", \"chromeos_components_unittests\": \"8853c6c1fc3e6e5a339d8722b0126e685d11e76e\", \"chromeos_unittests\": \"29758843cc757dcaa244b81b1bbbbd692194da23\", \"components_browsertests\": \"8a2d4b6aeadda27b8d0e1e73b0e48d94081f6659\", \"components_unittests\": \"5b8183a4b6c04d52aee01c53c3de97be96acee33\", \"compositor_unittests\": \"ad35efecf8aaef2a3adedef41faeef883f2b6421\", \"content_browsertests\": \"6f0f0357fa1d136b302041a8e4e77460b417518b\", \"content_unittests\": \"01cbff30ef6510d09c35c56911b7fec46ce30d47\", \"crashpad_tests\": \"817204909c2eae8979770fea84c84bb3b5540558\", \"crypto_unittests\": \"88d461dfce1e467aa7e80da91cb94ef5446f159b\", \"dbus_unittests\": \"8f5b8148f8aeb386ef850ecd44b2044592adb9c3\", \"device_unittests\": \"9dbee6ce61d8e842206c706c4e04deaba3f34d96\", \"display_unittests\": \"8e23e7a0407cec945c5dc2fb5229bdb9524e86c2\", \"events_unittests\": \"10aa70bdadecbc03715e528a387c8db87d5b5438\", \"exo_unittests\": \"d2ab29c09851f0b6028120d788453e344df852a3\", \"extensions_browsertests\": \"d82c993cf8d04731bd7c74a8ac6f58cf333f6610\", \"extensions_unittests\": \"6f1094f6eba7718445282772d4d4f9d4406af81c\", \"filesystem_service_unittests\": \"5b82b07312b73e00ee11538bcb96e6329a7a0dd0\", \"gcm_unit_tests\": \"c717accbfe947ec65d10911d36d69b9f1ff9bdcc\", \"gfx_unittests\": \"ab14b6be1a2b5f8d3cb9759b7e36280c999f9939\", \"gin_unittests\": \"f1063e4bf967c9ff19bd0da27d20d4eb4fccf64c\", \"gl_unittests_ozone\": \"8a6b548fcc3120311597221820c6de3b95a5e27a\", \"google_apis_unittests\": \"293782ceb2b6c2d2175607c4037c9fdf926efecc\", \"gpu_unittests\": \"2e2d4ba99bc045575dc616b39ba65803edc94b99\", \"gwp_asan_unittests\": \"be19e3d043cd2724f2d7a633d6c1d5be1f5da025\", \"interactive_ui_tests\": \"07c4d10a1d37b8630c246d5ed4f236c3f6e528f3\", \"ipc_tests\": \"d15ebc3e7d0e30985432d592391997cfbe8f13d1\", \"jingle_unittests\": \"29baf0673b5f9561673d8f9269673eb25dc39152\", \"keyboard_unittests\": \"6f413fea91e581a52e8fddc7821c519d97dbf495\", \"latency_unittests\": \"4b04f91391d758476ec07c6235e3530ff9e9b489\", \"libjingle_xmpp_unittests\": \"05d18dc18821895d58f99be585d4c3f29c5b3993\", \"liburlpattern_unittests\": \"d8ee6d4550ce95b9544819ea0dc2928d9497c8ac\", \"media_blink_unittests\": \"96e386706ae85e0ae4da214a3e37cf389dd66b3b\", \"media_unittests\": \"8215a970296d47b9d2d12d9009f71f4161737101\", \"message_center_unittests\": \"9a1a7c0d8e3c0993687990e70ddf468fb567f82f\", \"midi_unittests\": \"482f01ad25a9dd81fa13d8d648c8638dd76aa715\", \"mojo_core_unittests\": \"890983ff1c79e931ea96a0a788e5ebcec82d68d8\", \"mojo_unittests\": \"18fd01cf7809d54880be9f5be2835874dca0eda9\", \"nacl_helper_nonsfi_unittests\": \"971d2c10f92157b50416d2097da0ed9de8a47704\", \"nacl_loader_unittests\": \"c9e41e1d051e8070925f48b6a57172992faf9177\", \"native_theme_unittests\": \"ad7965ce0095388866dadb14c953ae046f12e673\", \"net_unittests\": \"519a861d95fb64cfa3c3163cb7a55589279e5be0\", \"ozone_gl_unittests\": \"14fbe38c893735ef0a0a0aae787e09687e3486aa\", \"ozone_unittests\": \"34c0ed0299c3d355838abb5e876770d059f4b3d2\", \"ozone_x11_unittests\": \"cb34b17b10a0c2bba60a8fefb93fde4d30640e84\", \"pdf_unittests\": \"65ddd844b6e80d150d8bf4e4a195779937d754e4\", \"perfetto_unittests\": \"54105638b0230aa8c4dda38a675770e7a24b40e2\", \"ppapi_unittests\": \"d900ca4fd6a104c454355ddb53e6c2b879a51eae\", \"printing_unittests\": \"989e672d4e103814c3c56315c1a26d8f7bc70aec\", \"remoting_unittests\": \"e6559e9d312175cbba719e46168c1369fe3f99c3\", \"sandbox_linux_unittests\": \"71525a408a4b6484fc8f54515c2ca696ea3cc4eb\", \"service_manager_unittests\": \"4b6efa3b4b2fa25d60be8ad076e33f5b5c0a2012\", \"services_unittests\": \"3efcab7c1b98aed09a1cc2082fe13a1fba2fd69d\", \"shell_dialogs_unittests\": \"0fc8da6d861776d3e6f864a8cc60a98b038354b7\", \"shell_encryption_unittests\": \"f642003b15ae8029d9543a28f6f6fced5c39311c\", \"skia_unittests\": \"d5fd3762a4720ea2e4a6993dc55e254c9f760425\", \"snapshot_unittests\": \"04667c0cf9a5d0ccf42670b4e5c7e7f772699378\", \"sql_unittests\": \"94a7f2e130ff2496755c7f2aa08fc7bead3acc3e\", \"storage_unittests\": \"49ad9683f2c14c6be2a5e15d2c8fd4a72a65d7c8\", \"sync_integration_tests\": \"f6fe3113ef74ada4ccfc05a282d2ff8737156ba9\", \"traffic_annotation_auditor_unittests\": \"5d6871bcf2b64f52e3851db5bfb99dec15b2102c\", \"ui_base_unittests\": \"3f59119e122e6f582663e9e8922ef9c425acbf67\", \"ui_chromeos_unittests\": \"eea73e46e4dec0b1af855043597afa21691f82ae\", \"ui_touch_selection_unittests\": \"1e20bff59f36c97187e44be2836682392b6ae78d\", \"unit_tests\": \"2d1d4886a3c4342fa4bd32bcde53eb1e52048396\", \"url_unittests\": \"0e2d40d8afad8fbfa87bae5d2edc63454d3d0176\", \"usage_time_limit_unittests\": \"e70ab63ee5cd4a339f6e00e548b427c29f1255db\", \"views_unittests\": \"ffba1938e95a2619e0ee9e9065641214cf41b1fe\", \"viz_unittests\": \"72c802a5573950524ab2baaf42b148af5b004904\", \"wm_unittests\": \"3eeff0c0f3f308a0e94d85167e6b18390cb33764\", \"wtf_unittests\": \"171a7643f05bec04e89ce671aa7932c764e604eb\", \"zlib_unittests\": \"ac0d6d27b43aed0bfee9f3dcae386d482b055658\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_1bdcc2c1d38386cf72a342843f986ec12bfab33633905ef2709b97cea999d0f5_v2\", \"builder_3df726b65531685b338b7c2caf48988c15becc2e6a96e549d7512dce09d4fac6_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_8851890e994e29b56fba2042f90feba99073c8e56d0ea67af5ee13ebe9f87cf6_v2\", \"builder_d1071779fa62103c126fbddd8eb4d0fa2c72f39bcf76fe56968d2607dc4a05dd_v2\", \"builder_db6a6033e460ced730b3940170fe4cf923f58c29fb56731f98470df5edf70e65_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-xenial-us-east1-d-8-61-5x80\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"e2-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-d\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611986477488467",
"status": "COMPLETED",
"status_changed_ts": "1611992643971677",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/22408",
"builder:linux-chromeos-dbg",
"buildset:commit/git/4278a7641b47ba40c9906e364e8d3dda9b5bb3d1",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4278a7641b47ba40c9906e364e8d3dda9b5bb3d1",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022133720103394016",
"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/8856703658142691280/+/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:516aaf2d27d0b010",
"user_agent:luci-scheduler"
],
"updated_ts": "1611992643976926",
"url": "https://ci.chromium.org/b/8856703658142691280",
"utcnow_ts": "1611992645318475"
}
}
[07:44:05.338] Invocation finished in 1h47m11.176907854s with status SUCCEEDED