[03:28:18.948] New invocation is queued and will start shortly
[03:28:20.057] Starting the invocation (attempt 1)
[03:28:20.097] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:28:20.097] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:28:20.098] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026944707620639184",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9026944707620639184",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e",
"gitiles_ref:refs/heads/master"
]
}
[03:28:21.632] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607398100251350",
"id": "8861514645589359824",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607398101356297",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21651",
"builder:linux-chromeos-dbg",
"buildset:commit/git/f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026944707620639184",
"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/8861514645589359824/+/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": "1607398101356493",
"url": "https://ci.chromium.org/b/8861514645589359824",
"utcnow_ts": "1607398101618793"
}
}
[03:28:21.632] Task URL: https://ci.chromium.org/b/8861514645589359824
[03:28:21.632] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:2:0) after 1m0s
[03:29:21.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:2:0)
[03:29:21.731] Timer tick, asking Buildbucket for the build status
[03:29:22.024] Build 8861514645589359824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:29:22.024] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:3:0) after 1m0s
[03:30:22.027] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:3:0)
[03:30:22.027] Timer tick, asking Buildbucket for the build status
[03:30:22.204] Build 8861514645589359824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:30:22.204] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:4:0) after 1m0s
[03:31:22.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:4:0)
[03:31:22.231] Timer tick, asking Buildbucket for the build status
[03:31:22.353] Build 8861514645589359824: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:31:22.353] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:5:0) after 1m0s
[03:31:26.879] Received PubSub notification, asking Buildbucket for the build status
[03:31:26.953] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:22.424] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:5:0)
[03:32:22.424] Timer tick, asking Buildbucket for the build status
[03:32:22.523] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:22.523] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:7:0) after 1m0s
[03:33:22.553] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:7:0)
[03:33:22.553] Timer tick, asking Buildbucket for the build status
[03:33:22.927] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:22.927] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:8:0) after 1m0s
[03:34:23.001] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:8:0)
[03:34:23.001] Timer tick, asking Buildbucket for the build status
[03:34:23.132] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:23.132] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:9:0) after 1m0s
[03:35:23.214] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:9:0)
[03:35:23.214] Timer tick, asking Buildbucket for the build status
[03:35:23.322] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:23.322] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:10:0) after 1m0s
[03:36:23.329] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:10:0)
[03:36:23.329] Timer tick, asking Buildbucket for the build status
[03:36:23.866] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:23.866] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:11:0) after 1m0s
[03:37:24.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:11:0)
[03:37:24.016] Timer tick, asking Buildbucket for the build status
[03:37:24.187] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:24.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:12:0) after 1m0s
[03:38:24.193] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:12:0)
[03:38:24.193] Timer tick, asking Buildbucket for the build status
[03:38:24.293] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:24.293] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:13:0) after 1m0s
[03:39:24.325] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:13:0)
[03:39:24.325] Timer tick, asking Buildbucket for the build status
[03:39:24.614] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:24.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:14:0) after 1m0s
[03:40:24.628] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:14:0)
[03:40:24.628] Timer tick, asking Buildbucket for the build status
[03:40:24.683] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:24.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:15:0) after 1m0s
[03:41:24.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:15:0)
[03:41:24.766] Timer tick, asking Buildbucket for the build status
[03:41:24.844] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:24.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:16:0) after 1m0s
[03:42:24.895] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:16:0)
[03:42:24.895] Timer tick, asking Buildbucket for the build status
[03:42:25.187] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:25.187] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:17:0) after 1m0s
[03:43:25.248] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:17:0)
[03:43:25.248] Timer tick, asking Buildbucket for the build status
[03:43:25.330] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:25.330] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:18:0) after 1m0s
[03:44:25.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:18:0)
[03:44:25.387] Timer tick, asking Buildbucket for the build status
[03:44:25.549] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:25.549] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:19:0) after 1m0s
[03:45:25.919] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:19:0)
[03:45:25.919] Timer tick, asking Buildbucket for the build status
[03:45:26.023] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:26.023] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:20:0) after 1m0s
[03:46:26.402] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:20:0)
[03:46:26.402] Timer tick, asking Buildbucket for the build status
[03:46:26.485] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:26.485] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:21:0) after 1m0s
[03:47:26.960] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:21:0)
[03:47:26.960] Timer tick, asking Buildbucket for the build status
[03:47:27.215] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:27.215] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:22:0) after 1m0s
[03:48:27.398] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:22:0)
[03:48:27.398] Timer tick, asking Buildbucket for the build status
[03:48:27.583] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:27.583] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:23:0) after 1m0s
[03:49:27.687] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:23:0)
[03:49:27.687] Timer tick, asking Buildbucket for the build status
[03:49:27.744] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:27.744] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:24:0) after 1m0s
[03:50:27.805] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:24:0)
[03:50:27.805] Timer tick, asking Buildbucket for the build status
[03:50:27.912] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:27.912] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:25:0) after 1m0s
[03:51:28.120] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:25:0)
[03:51:28.120] Timer tick, asking Buildbucket for the build status
[03:51:28.182] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:28.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:26:0) after 1m0s
[03:52:28.289] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:26:0)
[03:52:28.289] Timer tick, asking Buildbucket for the build status
[03:52:28.373] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:28.373] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:27:0) after 1m0s
[03:53:28.436] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:27:0)
[03:53:28.436] Timer tick, asking Buildbucket for the build status
[03:53:28.511] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:28.511] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:28:0) after 1m0s
[03:54:28.675] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:28:0)
[03:54:28.675] Timer tick, asking Buildbucket for the build status
[03:54:28.742] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:28.742] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:29:0) after 1m0s
[03:55:28.929] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:29:0)
[03:55:28.929] Timer tick, asking Buildbucket for the build status
[03:55:29.040] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:29.040] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:30:0) after 1m0s
[03:56:29.102] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:30:0)
[03:56:29.102] Timer tick, asking Buildbucket for the build status
[03:56:29.163] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:29.163] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:31:0) after 1m0s
[03:57:29.230] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:31:0)
[03:57:29.230] Timer tick, asking Buildbucket for the build status
[03:57:29.339] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:29.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:32:0) after 1m0s
[03:58:29.359] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:32:0)
[03:58:29.359] Timer tick, asking Buildbucket for the build status
[03:58:29.769] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:29.769] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:33:0) after 1m0s
[03:59:29.892] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:33:0)
[03:59:29.892] Timer tick, asking Buildbucket for the build status
[03:59:29.964] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:29.964] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:34:0) after 1m0s
[04:00:30.140] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:34:0)
[04:00:30.140] Timer tick, asking Buildbucket for the build status
[04:00:30.326] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:30.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:35:0) after 1m0s
[04:01:30.499] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:35:0)
[04:01:30.499] Timer tick, asking Buildbucket for the build status
[04:01:30.929] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:30.929] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:36:0) after 1m0s
[04:02:30.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:36:0)
[04:02:30.998] Timer tick, asking Buildbucket for the build status
[04:02:31.056] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:31.056] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:37:0) after 1m0s
[04:03:31.221] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:37:0)
[04:03:31.221] Timer tick, asking Buildbucket for the build status
[04:03:31.314] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:31.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:38:0) after 1m0s
[04:04:31.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:38:0)
[04:04:31.426] Timer tick, asking Buildbucket for the build status
[04:04:31.538] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:31.538] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:39:0) after 1m0s
[04:05:31.598] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:39:0)
[04:05:31.598] Timer tick, asking Buildbucket for the build status
[04:05:31.821] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:31.821] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:40:0) after 1m0s
[04:06:31.880] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:40:0)
[04:06:31.880] Timer tick, asking Buildbucket for the build status
[04:06:32.252] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:32.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:41:0) after 1m0s
[04:07:32.275] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:41:0)
[04:07:32.275] Timer tick, asking Buildbucket for the build status
[04:07:32.471] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:32.471] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:42:0) after 1m0s
[04:08:32.558] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:42:0)
[04:08:32.558] Timer tick, asking Buildbucket for the build status
[04:08:33.249] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:33.249] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:43:0) after 1m0s
[04:09:33.231] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:43:0)
[04:09:33.231] Timer tick, asking Buildbucket for the build status
[04:09:33.334] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:33.334] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:44:0) after 1m0s
[04:10:33.363] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:44:0)
[04:10:33.363] Timer tick, asking Buildbucket for the build status
[04:10:33.443] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:33.443] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:45:0) after 1m0s
[04:11:33.524] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:45:0)
[04:11:33.524] Timer tick, asking Buildbucket for the build status
[04:11:33.588] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:33.588] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:46:0) after 1m0s
[04:12:33.642] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:46:0)
[04:12:33.642] Timer tick, asking Buildbucket for the build status
[04:12:33.847] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:33.847] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:47:0) after 1m0s
[04:13:33.936] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:47:0)
[04:13:33.936] Timer tick, asking Buildbucket for the build status
[04:13:34.050] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:34.050] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:48:0) after 1m0s
[04:14:34.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:48:0)
[04:14:34.123] Timer tick, asking Buildbucket for the build status
[04:14:34.182] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:34.182] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:49:0) after 1m0s
[04:15:34.220] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:49:0)
[04:15:34.221] Timer tick, asking Buildbucket for the build status
[04:15:34.476] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:34.476] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:50:0) after 1m0s
[04:16:34.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:50:0)
[04:16:34.497] Timer tick, asking Buildbucket for the build status
[04:16:34.856] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:34.856] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:51:0) after 1m0s
[04:17:34.987] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:51:0)
[04:17:34.987] Timer tick, asking Buildbucket for the build status
[04:17:35.073] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:35.073] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:52:0) after 1m0s
[04:18:35.161] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:52:0)
[04:18:35.161] Timer tick, asking Buildbucket for the build status
[04:18:35.280] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:35.280] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:53:0) after 1m0s
[04:19:35.353] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:53:0)
[04:19:35.353] Timer tick, asking Buildbucket for the build status
[04:19:35.462] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:35.462] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:54:0) after 1m0s
[04:20:35.525] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:54:0)
[04:20:35.525] Timer tick, asking Buildbucket for the build status
[04:20:36.825] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:36.825] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:55:0) after 1m0s
[04:21:36.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:55:0)
[04:21:36.856] Timer tick, asking Buildbucket for the build status
[04:21:36.931] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:36.931] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:56:0) after 1m0s
[04:22:36.970] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:56:0)
[04:22:36.970] Timer tick, asking Buildbucket for the build status
[04:22:37.100] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:37.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:57:0) after 1m0s
[04:23:37.122] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:57:0)
[04:23:37.122] Timer tick, asking Buildbucket for the build status
[04:23:37.264] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:37.264] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:58:0) after 1m0s
[04:24:37.286] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:58:0)
[04:24:37.286] Timer tick, asking Buildbucket for the build status
[04:24:37.368] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:37.368] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:59:0) after 1m0s
[04:25:37.392] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:59:0)
[04:25:37.392] Timer tick, asking Buildbucket for the build status
[04:25:37.544] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:37.544] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:60:0) after 1m0s
[04:26:37.595] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:60:0)
[04:26:37.595] Timer tick, asking Buildbucket for the build status
[04:26:37.648] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:37.648] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:61:0) after 1m0s
[04:27:37.689] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:61:0)
[04:27:37.689] Timer tick, asking Buildbucket for the build status
[04:27:38.038] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:38.038] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:62:0) after 1m0s
[04:28:38.145] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:62:0)
[04:28:38.145] Timer tick, asking Buildbucket for the build status
[04:28:38.281] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:38.281] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:63:0) after 1m0s
[04:29:38.341] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:63:0)
[04:29:38.341] Timer tick, asking Buildbucket for the build status
[04:29:38.449] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:38.449] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:64:0) after 1m0s
[04:30:38.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:64:0)
[04:30:38.467] Timer tick, asking Buildbucket for the build status
[04:30:38.545] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:38.545] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:65:0) after 1m0s
[04:31:38.715] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:65:0)
[04:31:38.715] Timer tick, asking Buildbucket for the build status
[04:31:39.167] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:39.167] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:66:0) after 1m0s
[04:32:39.387] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:66:0)
[04:32:39.387] Timer tick, asking Buildbucket for the build status
[04:32:39.453] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:39.453] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:67:0) after 1m0s
[04:33:39.469] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:67:0)
[04:33:39.469] Timer tick, asking Buildbucket for the build status
[04:33:39.598] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:39.598] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:68:0) after 1m0s
[04:34:39.621] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:68:0)
[04:34:39.621] Timer tick, asking Buildbucket for the build status
[04:34:39.877] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:39.877] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:69:0) after 1m0s
[04:35:39.935] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:69:0)
[04:35:39.935] Timer tick, asking Buildbucket for the build status
[04:35:40.047] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:40.047] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:70:0) after 1m0s
[04:36:40.066] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:70:0)
[04:36:40.066] Timer tick, asking Buildbucket for the build status
[04:36:40.219] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:40.219] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:71:0) after 1m0s
[04:37:40.247] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:71:0)
[04:37:40.247] Timer tick, asking Buildbucket for the build status
[04:37:40.706] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:40.706] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:72:0) after 1m0s
[04:38:40.804] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:72:0)
[04:38:40.804] Timer tick, asking Buildbucket for the build status
[04:38:41.041] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:41.041] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:73:0) after 1m0s
[04:39:41.118] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:73:0)
[04:39:41.118] Timer tick, asking Buildbucket for the build status
[04:39:41.248] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:41.248] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:74:0) after 1m0s
[04:40:41.321] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:74:0)
[04:40:41.322] Timer tick, asking Buildbucket for the build status
[04:40:41.493] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:41.493] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:75:0) after 1m0s
[04:41:41.622] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:75:0)
[04:41:41.622] Timer tick, asking Buildbucket for the build status
[04:41:41.725] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:41.725] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:76:0) after 1m0s
[04:42:41.816] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:76:0)
[04:42:41.816] Timer tick, asking Buildbucket for the build status
[04:42:42.723] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:42.723] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:77:0) after 1m0s
[04:43:42.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:77:0)
[04:43:42.747] Timer tick, asking Buildbucket for the build status
[04:43:42.844] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:42.844] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:78:0) after 1m0s
[04:44:42.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:78:0)
[04:44:42.879] Timer tick, asking Buildbucket for the build status
[04:44:43.252] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:43.252] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:79:0) after 1m0s
[04:45:43.273] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:79:0)
[04:45:43.273] Timer tick, asking Buildbucket for the build status
[04:45:43.403] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:43.403] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:80:0) after 1m0s
[04:46:43.426] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:80:0)
[04:46:43.426] Timer tick, asking Buildbucket for the build status
[04:46:43.694] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:43.694] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:81:0) after 1m0s
[04:47:43.735] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:81:0)
[04:47:43.735] Timer tick, asking Buildbucket for the build status
[04:47:43.833] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:43.833] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:82:0) after 1m0s
[04:48:43.864] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:82:0)
[04:48:43.864] Timer tick, asking Buildbucket for the build status
[04:48:44.240] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:44.240] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:83:0) after 1m0s
[04:49:44.306] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:83:0)
[04:49:44.306] Timer tick, asking Buildbucket for the build status
[04:49:44.653] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:44.653] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:84:0) after 1m0s
[04:50:44.766] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:84:0)
[04:50:44.766] Timer tick, asking Buildbucket for the build status
[04:50:44.830] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:44.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:85:0) after 1m0s
[04:51:44.818] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:85:0)
[04:51:44.818] Timer tick, asking Buildbucket for the build status
[04:51:44.890] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:44.890] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:86:0) after 1m0s
[04:52:44.985] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:86:0)
[04:52:44.985] Timer tick, asking Buildbucket for the build status
[04:52:45.094] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:45.094] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:87:0) after 1m0s
[04:53:45.124] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:87:0)
[04:53:45.124] Timer tick, asking Buildbucket for the build status
[04:53:45.246] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:45.246] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:88:0) after 1m0s
[04:54:45.280] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:88:0)
[04:54:45.280] Timer tick, asking Buildbucket for the build status
[04:54:45.336] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:45.336] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:89:0) after 1m0s
[04:55:45.394] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:89:0)
[04:55:45.394] Timer tick, asking Buildbucket for the build status
[04:55:45.455] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:45.455] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:90:0) after 1m0s
[04:56:45.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:90:0)
[04:56:45.489] Timer tick, asking Buildbucket for the build status
[04:56:45.563] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:45.564] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:91:0) after 1m0s
[04:57:45.618] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:91:0)
[04:57:45.618] Timer tick, asking Buildbucket for the build status
[04:57:46.339] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:46.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:92:0) after 1m0s
[04:58:46.376] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:92:0)
[04:58:46.376] Timer tick, asking Buildbucket for the build status
[04:58:46.481] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:46.481] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:93:0) after 1m0s
[04:59:46.538] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:93:0)
[04:59:46.538] Timer tick, asking Buildbucket for the build status
[04:59:46.614] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:46.614] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:94:0) after 1m0s
[05:00:46.666] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:94:0)
[05:00:46.666] Timer tick, asking Buildbucket for the build status
[05:00:46.765] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:46.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:95:0) after 1m0s
[05:01:46.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:95:0)
[05:01:46.757] Timer tick, asking Buildbucket for the build status
[05:01:46.830] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:46.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:96:0) after 1m0s
[05:02:46.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:96:0)
[05:02:46.874] Timer tick, asking Buildbucket for the build status
[05:02:46.962] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:46.962] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:97:0) after 1m0s
[05:03:47.018] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:97:0)
[05:03:47.018] Timer tick, asking Buildbucket for the build status
[05:03:47.164] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:47.164] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:98:0) after 1m0s
[05:04:47.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:98:0)
[05:04:47.203] Timer tick, asking Buildbucket for the build status
[05:04:47.665] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:47.665] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:99:0) after 1m0s
[05:05:47.668] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:99:0)
[05:05:47.668] Timer tick, asking Buildbucket for the build status
[05:05:47.743] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:47.743] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:100:0) after 1m0s
[05:06:47.798] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:100:0)
[05:06:47.798] Timer tick, asking Buildbucket for the build status
[05:06:47.913] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:47.913] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:101:0) after 1m0s
[05:07:47.974] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:101:0)
[05:07:47.974] Timer tick, asking Buildbucket for the build status
[05:07:48.076] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:48.076] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:102:0) after 1m0s
[05:08:48.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:102:0)
[05:08:48.104] Timer tick, asking Buildbucket for the build status
[05:08:48.208] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:48.208] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:103:0) after 1m0s
[05:09:48.224] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:103:0)
[05:09:48.224] Timer tick, asking Buildbucket for the build status
[05:09:48.314] Build 8861514645589359824: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:48.314] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9026944707620639184:104:0) after 1m0s
[05:10:21.247] Received PubSub notification, asking Buildbucket for the build status
[05:10:21.345] Build 8861514645589359824: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:10:21.345] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607404220605394",
"created_by": "project:chromium",
"created_ts": "1607398100251350",
"id": "8861514645589359824",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e\", \"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-central1-b-8-210-pkfu\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21651, \"got_angle_revision\": \"8b4990bbd6217138d30e19f5309560cc6e2592a0\", \"got_dawn_revision\": \"8a73e1876dbd2d862701fde91bc21630c4bd0e5b\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e\", \"got_revision_cp\": \"refs/heads/master@{#834546}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"84b9bb6621c7eae8da034d31aceab780d8b1abdc\", \"got_v8_revision\": \"8c0eca5f170cfe786bb807f359489e3db7d5b9ce\", \"got_v8_revision_cp\": \"refs/heads/8.9.119@{#1}\", \"got_webrtc_revision\": \"bb1a28de3cf17476142e9de8ee12fe6c567d06c1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32789}\", \"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\": \"f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e\", \"swarm_hashes_refs/heads/master(at){#834546}_without_patch\": {\"absl_hardening_tests\": \"de45fcb557a42618f4b3d1d36f619c6cafd3027a\", \"accessibility_unittests\": \"8e157ec91bae13284ec40ed3b855f1d73c1d954a\", \"angle_unittests\": \"e588a26ed6a5abbc1c0ad26de799017d46881a28\", \"app_list_unittests\": \"8de1c9d9d2392a18d70423559cc04505e843587a\", \"app_shell_unittests\": \"40864a620280e60a9cc5f62b652a4cd88c434161\", \"ash_unittests\": \"c9ac6c505461947d90e5f356a04e6d97cb7da5b9\", \"aura_unittests\": \"09974992e82bc66445feae3afacc71338d48def0\", \"base_unittests\": \"9cc5bc7c1f76f20cb19dd4a909c87802e598af84\", \"base_util_unittests\": \"86e15e1a4a82c7b8abfb55ffb01f86236bc48d1e\", \"blink_common_unittests\": \"b9f46b3dfe8a66e123b59c6a287719da0cc8f39e\", \"blink_fuzzer_unittests\": \"800ff5487e07113c524d9c83c70d88644094b7c4\", \"blink_heap_unittests\": \"a8f312edc7fd2b518820af9aad28ff9a7e726b4b\", \"blink_platform_unittests\": \"1b810267a48bae6690ec835b783764f48d991c09\", \"blink_unittests\": \"578a3b0724aa35a3e7fb96bd61619f5ddf02e018\", \"boringssl_crypto_tests\": \"c3713a1a381d3e0c9daf6a9f21d1bee5b886fb6f\", \"boringssl_ssl_tests\": \"16afb6254c07d83eba4e7b539a4e891ac90ad7fd\", \"browser_tests\": \"03cb63a1ffc85294ebe65a51e4919806d8262af1\", \"capture_unittests\": \"26fa1f22aa4e8b48f84fe9ae3f0d39e35849b3ce\", \"cast_unittests\": \"6845523afe09f77e595a494c405cbcdb49dde166\", \"cc_unittests\": \"ff61491220d5bfbbc6a5315c698a43be1113bd67\", \"chrome_app_unittests\": \"5ce7411805cc9527e33651180987f06c25ce6dcc\", \"chromedriver_unittests\": \"8b8673f5a1807727bdc442d0791d9f9166811491\", \"chromeos_components_unittests\": \"64a2b613b3ec22c0782d0adfabf127581df8996d\", \"chromeos_unittests\": \"668b4826f2841d3be97a8c0b88864450b9f6ea39\", \"components_browsertests\": \"23a765b510ea00c1df06596b9add6a0eb81e29dc\", \"components_unittests\": \"c5840b30ed63651db1b7a1afe821d0ece90d941c\", \"compositor_unittests\": \"fb8d506374eff6aff31b3df24ffabf32165be2cf\", \"content_browsertests\": \"3813ee98f4c1b9fcde256283ed75b5dd623d0106\", \"content_unittests\": \"948e67cf1b3bc470795d6a0426ff8b3fecfe3bc7\", \"crashpad_tests\": \"4c33124c16dec97b7129ef20f02daf07a2524371\", \"crypto_unittests\": \"850380aa0440cd92816364b1b4a4ecce49a8fe57\", \"dbus_unittests\": \"202b09a90929917c290db6228dd9c8c5e749626c\", \"device_unittests\": \"d5d501165c8a15b847af4b473ca11207a3506863\", \"display_unittests\": \"097b9f15ba76b85684679d2fc6f504b0b1c37c0c\", \"events_unittests\": \"3f4c8d3e13b1570af52e16b8d5ed83f98a74596b\", \"exo_unittests\": \"96dbc5313289b10a55832a9c93205ca450902ec9\", \"extensions_browsertests\": \"173950224c10ef22fd4fa4e54f96867adafc9157\", \"extensions_unittests\": \"90bb2fa9d361da15942a27ee8ddfe8b9ae73fe16\", \"filesystem_service_unittests\": \"f62c91d15277c72f4d5578d23486b4d6a0885ec2\", \"gcm_unit_tests\": \"a4d4c6ade4daa34e99bf54159a1bf5d7877a912c\", \"gfx_unittests\": \"f19d4ba53f3373747d90d35fb833765d4160efc2\", \"gin_unittests\": \"86c816b367bcf125ae01e3de96516a46b6ba6944\", \"gl_unittests_ozone\": \"ae6d731184a9d21aa009fecf2d01fa496159916f\", \"google_apis_unittests\": \"693c7683fb2dc2d58f650256a3f60c502a7107a8\", \"gpu_unittests\": \"a04a07dbd1c014af324002378d1e554172a13f4f\", \"gwp_asan_unittests\": \"e4fb8614b4dfe435ceba216d03f0051bbb136592\", \"interactive_ui_tests\": \"6a9dfdd2b4b15126f37b1af4ddc3b9f0a9eed210\", \"ipc_tests\": \"e12b4befc238eef1c7d7f5556e87389040d570d1\", \"jingle_unittests\": \"8b2781f38437eb8b47e5e7daaafda6caa86df19e\", \"keyboard_unittests\": \"965f69a37fc784fc965c3707f647500eb33d9038\", \"latency_unittests\": \"c823b81b662c3f31ec41fb73755a4b14e650d4c1\", \"libjingle_xmpp_unittests\": \"50f077b13c3da4e69cd3b930f7c17ecaff85de2d\", \"liburlpattern_unittests\": \"26df8234d12cb8bff2c75849f33525f339b4c25c\", \"media_blink_unittests\": \"880c5c83f57fc468d3593e8a525d536b4d6ccf9c\", \"media_unittests\": \"c53618d402fe275131c844350bb5b34a2c29abe7\", \"message_center_unittests\": \"3a249b1a38c4d08b93cbfe18d605cf7fc82aae9b\", \"midi_unittests\": \"9d269a3b37e944fe9943d55c3ac453bde077fbb6\", \"mojo_core_unittests\": \"033e8dfe285d04a3dcff751b3298ca318b9ed3fa\", \"mojo_unittests\": \"a4b4a6fcb56e588f7943110ff462bf3a7c04d112\", \"nacl_helper_nonsfi_unittests\": \"90d2e04738f0af6b51962eb90b0f5d54ec82709c\", \"nacl_loader_unittests\": \"c24ba990a988f515399216529deb2d984a0314ac\", \"native_theme_unittests\": \"0f3b2c8a311ef1b1556c9a34b4fbf9b0ac996df9\", \"net_unittests\": \"baea352a50d55b0a4bee01fcb15e68bb573c29d6\", \"ozone_gl_unittests\": \"f00ac6f19496914b694fef76def92a3f99611394\", \"ozone_unittests\": \"eb9427ad849ef03313a19850b441bb0b12d2e7ad\", \"ozone_x11_unittests\": \"a4b0e9fd4a55191331d3e4d7ba883bf83012aec3\", \"pdf_unittests\": \"0b06182318a096aa22dbbf981763e26184e42c92\", \"perfetto_unittests\": \"0bb4788ab54ad379bb1f1c93007428f8735c0c34\", \"ppapi_unittests\": \"ff05bff518f4d760613f77149a0394d86cc7731c\", \"printing_unittests\": \"3a0986473456970b2955ea9df120a9316e3c5076\", \"remoting_unittests\": \"fd34a64db1854b4f6ebb699d9fab844c2f839131\", \"sandbox_linux_unittests\": \"45a76c60d07e567b89d9e3479bfde7488d5ad6ad\", \"service_manager_unittests\": \"6766dec2120003028e39a373b5af51371a7fc9bd\", \"services_unittests\": \"547050695dfd50ae8b81363059502c50bfd61035\", \"shell_dialogs_unittests\": \"d1544987ccb30eacc0f78ca51c4efd022be74ded\", \"shell_encryption_unittests\": \"042562848d30ad40af00d6ce25cc9c9dbc7ff2f1\", \"skia_unittests\": \"0934673fc58bef5cddd8f58515e3b11eedc5b3d1\", \"snapshot_unittests\": \"93446db5f8398ef60e002b8205d13821bcffe2a4\", \"sql_unittests\": \"466b2b1f2c795a8f059916eb393d6d403386b35a\", \"storage_unittests\": \"e3f87a12372837174a236a6f7c264e03414af415\", \"sync_integration_tests\": \"eeffcc974ad5f94007011b81b648fcbc379baa25\", \"traffic_annotation_auditor_unittests\": \"ef2ae64814d834f74f79eacbf0f94a238c6cc7a8\", \"ui_base_unittests\": \"d45633476bcc8b4fe8c69fc39059550a94c6259b\", \"ui_chromeos_unittests\": \"7e2c30680ea2f6723d397d6afe94a79cf3743588\", \"ui_touch_selection_unittests\": \"1792ff61b6614d490ca1cc96b63247833154b819\", \"unit_tests\": \"c54a49b93673ff695ff6b18b954e05583cf7365a\", \"url_unittests\": \"35f9fd55be885a0d78fce2c9447ab948bf5fc546\", \"usage_time_limit_unittests\": \"146cfdd0b8e2e357dec356f263f87185fd1c6b7d\", \"views_unittests\": \"847c8d7685e7fe8e128489c3374afc7e9dbbcd10\", \"viz_unittests\": \"aa4b7099cb210846e82cece8905ea0f37109b33a\", \"wm_unittests\": \"2f659381124c57cb6bc22a5c5915addb1eaf5b08\", \"wtf_unittests\": \"3e5dbf6094bb784d467007255920014bdc13449d\", \"zlib_unittests\": \"7ec9bd5889187891b67a890db32a67069e67aa8a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4973d939604d11dcbaec68575e69c80c756ff63e355ab013b29b8bca26434b7c_v2\", \"builder_6887d226fea03ae8e203d0a1d4c9e197b929dd99abf2bafb38063e06e39bc1ba_v2\", \"builder_8ed59a4fa98a52009c5b7be7e9c90826b738aab438c85f8db549ef122247493f_v2\", \"builder_94dc7d486ab1bafc5bfb11010ef18f0cf20e8e07ae86b58c09f57ba294c280ac_v2\", \"builder_e3c511bab72b23733448544bf03eead15dcea51fe3ad494bd400cacf7c8c4bfb_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-central1-b-8-210-pkfu\"], \"image\": [\"chrome-xenial-20112900-2f7c54bbcac\"], \"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\": [\"5640-d0eb544\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607398286291184",
"status": "COMPLETED",
"status_changed_ts": "1607404220830096",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21651",
"builder:linux-chromeos-dbg",
"buildset:commit/git/f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f8cafb1d9cb65b9edd810ffe31c6bd5ce667927e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026944707620639184",
"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/8861514645589359824/+/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:5059361b08cc6e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607404220835111",
"url": "https://ci.chromium.org/b/8861514645589359824",
"utcnow_ts": "1607404221332527"
}
}
[05:10:21.345] Invocation finished in 1h42m2.415155331s with status SUCCEEDED