[03:39:58.092] New invocation is queued and will start shortly
[03:39:59.198] Starting the invocation (attempt 1)
[03:39:59.234] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:39:59.234] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:39:59.235] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030114868337191056",
"parameters_json": "{\"builder_name\":\"linux-chromeos-dbg\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-chromeos-dbg",
"scheduler_invocation_id:9030114868337191056",
"scheduler_job_id:chromium/linux-chromeos-dbg",
"user_agent:luci-scheduler",
"buildset:commit/git/54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62",
"gitiles_ref:refs/heads/master"
]
}
[03:39:59.932] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604374799343300",
"id": "8864684806362262224",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604374799766166",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21122",
"builder:linux-chromeos-dbg",
"buildset:commit/git/54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030114868337191056",
"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/8864684806362262224/+/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": "1604374799766315",
"url": "https://ci.chromium.org/b/8864684806362262224",
"utcnow_ts": "1604374799926147"
}
}
[03:39:59.932] Task URL: https://ci.chromium.org/b/8864684806362262224
[03:39:59.932] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:2:0) after 1m0s
[03:41:00.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:2:0)
[03:41:00.016] Timer tick, asking Buildbucket for the build status
[03:41:00.299] Build 8864684806362262224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:41:00.299] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:3:0) after 1m0s
[03:42:00.319] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:3:0)
[03:42:00.319] Timer tick, asking Buildbucket for the build status
[03:42:00.565] Build 8864684806362262224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:42:00.565] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:4:0) after 1m0s
[03:43:00.600] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:4:0)
[03:43:00.600] Timer tick, asking Buildbucket for the build status
[03:43:01.139] Build 8864684806362262224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:43:01.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:5:0) after 1m0s
[03:44:01.474] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:5:0)
[03:44:01.474] Timer tick, asking Buildbucket for the build status
[03:44:01.548] Build 8864684806362262224: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[03:44:01.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:6:0) after 1m0s
[03:44:05.206] Received PubSub notification, asking Buildbucket for the build status
[03:44:05.277] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:01.986] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:6:0)
[03:45:01.987] Timer tick, asking Buildbucket for the build status
[03:45:02.349] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:02.349] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:8:0) after 1m0s
[03:46:02.755] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:8:0)
[03:46:02.755] Timer tick, asking Buildbucket for the build status
[03:46:02.954] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:02.955] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:9:0) after 1m0s
[03:47:03.016] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:9:0)
[03:47:03.016] Timer tick, asking Buildbucket for the build status
[03:47:03.413] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:03.413] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:10:0) after 1m0s
[03:48:03.484] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:10:0)
[03:48:03.485] Timer tick, asking Buildbucket for the build status
[03:48:03.675] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:03.675] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:11:0) after 1m0s
[03:49:03.712] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:11:0)
[03:49:03.712] Timer tick, asking Buildbucket for the build status
[03:49:03.830] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:03.830] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:12:0) after 1m0s
[03:50:03.874] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:12:0)
[03:50:03.874] Timer tick, asking Buildbucket for the build status
[03:50:04.114] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:04.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:13:0) after 1m0s
[03:51:04.172] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:13:0)
[03:51:04.172] Timer tick, asking Buildbucket for the build status
[03:51:04.416] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:04.416] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:14:0) after 1m0s
[03:52:04.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:14:0)
[03:52:04.534] Timer tick, asking Buildbucket for the build status
[03:52:04.740] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:04.740] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:15:0) after 1m0s
[03:53:04.793] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:15:0)
[03:53:04.793] Timer tick, asking Buildbucket for the build status
[03:53:05.228] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:05.228] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:16:0) after 1m0s
[03:54:05.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:16:0)
[03:54:05.368] Timer tick, asking Buildbucket for the build status
[03:54:05.480] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:05.480] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:17:0) after 1m0s
[03:55:05.536] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:17:0)
[03:55:05.536] Timer tick, asking Buildbucket for the build status
[03:55:06.133] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:06.133] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:18:0) after 1m0s
[03:56:06.228] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:18:0)
[03:56:06.228] Timer tick, asking Buildbucket for the build status
[03:56:06.367] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:06.367] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:19:0) after 1m0s
[03:57:06.452] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:19:0)
[03:57:06.452] Timer tick, asking Buildbucket for the build status
[03:57:06.581] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:06.581] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:20:0) after 1m0s
[03:58:06.620] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:20:0)
[03:58:06.620] Timer tick, asking Buildbucket for the build status
[03:58:06.695] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:06.695] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:21:0) after 1m0s
[03:59:06.700] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:21:0)
[03:59:06.700] Timer tick, asking Buildbucket for the build status
[03:59:06.899] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:06.899] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:22:0) after 1m0s
[04:00:06.922] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:22:0)
[04:00:06.922] Timer tick, asking Buildbucket for the build status
[04:00:07.226] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:07.226] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:23:0) after 1m0s
[04:01:07.421] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:23:0)
[04:01:07.421] Timer tick, asking Buildbucket for the build status
[04:01:07.492] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:07.492] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:24:0) after 1m0s
[04:02:07.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:24:0)
[04:02:07.623] Timer tick, asking Buildbucket for the build status
[04:02:07.708] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:07.708] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:25:0) after 1m0s
[04:03:07.731] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:25:0)
[04:03:07.731] Timer tick, asking Buildbucket for the build status
[04:03:07.807] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:07.807] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:26:0) after 1m0s
[04:04:07.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:26:0)
[04:04:07.906] Timer tick, asking Buildbucket for the build status
[04:04:07.972] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:07.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:27:0) after 1m0s
[04:05:08.115] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:27:0)
[04:05:08.115] Timer tick, asking Buildbucket for the build status
[04:05:08.262] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:08.262] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:28:0) after 1m0s
[04:06:08.295] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:28:0)
[04:06:08.295] Timer tick, asking Buildbucket for the build status
[04:06:08.378] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:08.378] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:29:0) after 1m0s
[04:07:08.495] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:29:0)
[04:07:08.495] Timer tick, asking Buildbucket for the build status
[04:07:08.577] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:08.577] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:30:0) after 1m0s
[04:08:08.692] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:30:0)
[04:08:08.692] Timer tick, asking Buildbucket for the build status
[04:08:09.084] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:09.084] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:31:0) after 1m0s
[04:09:09.154] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:31:0)
[04:09:09.154] Timer tick, asking Buildbucket for the build status
[04:09:09.234] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:09.234] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:32:0) after 1m0s
[04:10:09.305] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:32:0)
[04:10:09.305] Timer tick, asking Buildbucket for the build status
[04:10:09.433] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:09.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:33:0) after 1m0s
[04:11:09.534] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:33:0)
[04:11:09.534] Timer tick, asking Buildbucket for the build status
[04:11:09.977] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:09.977] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:34:0) after 1m0s
[04:12:10.021] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:34:0)
[04:12:10.021] Timer tick, asking Buildbucket for the build status
[04:12:10.305] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:10.305] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:35:0) after 1m0s
[04:13:10.368] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:35:0)
[04:13:10.368] Timer tick, asking Buildbucket for the build status
[04:13:10.422] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:10.422] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:36:0) after 1m0s
[04:14:10.577] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:36:0)
[04:14:10.577] Timer tick, asking Buildbucket for the build status
[04:14:10.651] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:10.652] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:37:0) after 1m0s
[04:15:11.028] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:37:0)
[04:15:11.028] Timer tick, asking Buildbucket for the build status
[04:15:11.169] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:11.169] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:38:0) after 1m0s
[04:16:11.198] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:38:0)
[04:16:11.198] Timer tick, asking Buildbucket for the build status
[04:16:11.253] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:11.253] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:39:0) after 1m0s
[04:17:11.385] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:39:0)
[04:17:11.385] Timer tick, asking Buildbucket for the build status
[04:17:11.685] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:11.685] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:40:0) after 1m0s
[04:18:11.694] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:40:0)
[04:18:11.694] Timer tick, asking Buildbucket for the build status
[04:18:11.765] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:11.765] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:41:0) after 1m0s
[04:19:11.962] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:41:0)
[04:19:11.962] Timer tick, asking Buildbucket for the build status
[04:19:12.034] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:12.034] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:42:0) after 1m0s
[04:20:12.464] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:42:0)
[04:20:12.464] Timer tick, asking Buildbucket for the build status
[04:20:13.642] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:13.642] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:43:0) after 1m0s
[04:21:13.777] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:43:0)
[04:21:13.777] Timer tick, asking Buildbucket for the build status
[04:21:14.006] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:14.006] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:44:0) after 1m0s
[04:22:14.070] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:44:0)
[04:22:14.070] Timer tick, asking Buildbucket for the build status
[04:22:14.143] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:14.143] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:45:0) after 1m0s
[04:23:14.252] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:45:0)
[04:23:14.252] Timer tick, asking Buildbucket for the build status
[04:23:14.369] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:14.369] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:46:0) after 1m0s
[04:24:14.371] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:46:0)
[04:24:14.371] Timer tick, asking Buildbucket for the build status
[04:24:14.683] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:14.683] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:47:0) after 1m0s
[04:25:14.757] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:47:0)
[04:25:14.757] Timer tick, asking Buildbucket for the build status
[04:25:14.876] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:14.876] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:48:0) after 1m0s
[04:26:14.911] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:48:0)
[04:26:14.911] Timer tick, asking Buildbucket for the build status
[04:26:15.009] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:15.009] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:49:0) after 1m0s
[04:27:15.267] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:49:0)
[04:27:15.267] Timer tick, asking Buildbucket for the build status
[04:27:15.399] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:15.399] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:50:0) after 1m0s
[04:28:15.467] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:50:0)
[04:28:15.467] Timer tick, asking Buildbucket for the build status
[04:28:15.534] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:15.534] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:51:0) after 1m0s
[04:29:15.681] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:51:0)
[04:29:15.681] Timer tick, asking Buildbucket for the build status
[04:29:15.805] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:15.805] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:52:0) after 1m0s
[04:30:15.877] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:52:0)
[04:30:15.877] Timer tick, asking Buildbucket for the build status
[04:30:16.048] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:16.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:53:0) after 1m0s
[04:31:16.110] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:53:0)
[04:31:16.110] Timer tick, asking Buildbucket for the build status
[04:31:16.883] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:16.883] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:54:0) after 1m0s
[04:32:16.965] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:54:0)
[04:32:16.965] Timer tick, asking Buildbucket for the build status
[04:32:17.082] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:17.082] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:55:0) after 1m0s
[04:33:17.190] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:55:0)
[04:33:17.191] Timer tick, asking Buildbucket for the build status
[04:33:17.288] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:17.288] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:56:0) after 1m0s
[04:34:17.297] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:56:0)
[04:34:17.297] Timer tick, asking Buildbucket for the build status
[04:34:17.570] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:17.570] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:57:0) after 1m0s
[04:35:17.683] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:57:0)
[04:35:17.683] Timer tick, asking Buildbucket for the build status
[04:35:17.804] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:17.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:58:0) after 1m0s
[04:36:17.823] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:58:0)
[04:36:17.823] Timer tick, asking Buildbucket for the build status
[04:36:17.942] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:17.942] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:59:0) after 1m0s
[04:37:18.025] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:59:0)
[04:37:18.025] Timer tick, asking Buildbucket for the build status
[04:37:18.166] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:18.166] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:60:0) after 1m0s
[04:38:18.207] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:60:0)
[04:38:18.207] Timer tick, asking Buildbucket for the build status
[04:38:18.339] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:18.339] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:61:0) after 1m0s
[04:39:18.367] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:61:0)
[04:39:18.367] Timer tick, asking Buildbucket for the build status
[04:39:18.463] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:18.463] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:62:0) after 1m0s
[04:40:18.516] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:62:0)
[04:40:18.516] Timer tick, asking Buildbucket for the build status
[04:40:18.618] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:18.618] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:63:0) after 1m0s
[04:41:18.634] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:63:0)
[04:41:18.634] Timer tick, asking Buildbucket for the build status
[04:41:19.293] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:19.293] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:64:0) after 1m0s
[04:42:19.316] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:64:0)
[04:42:19.316] Timer tick, asking Buildbucket for the build status
[04:42:19.509] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:19.509] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:65:0) after 1m0s
[04:43:19.549] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:65:0)
[04:43:19.549] Timer tick, asking Buildbucket for the build status
[04:43:19.670] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:19.670] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:66:0) after 1m0s
[04:44:19.693] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:66:0)
[04:44:19.693] Timer tick, asking Buildbucket for the build status
[04:44:19.781] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:19.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:67:0) after 1m0s
[04:45:19.808] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:67:0)
[04:45:19.808] Timer tick, asking Buildbucket for the build status
[04:45:19.928] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:19.928] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:68:0) after 1m0s
[04:46:20.030] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:68:0)
[04:46:20.030] Timer tick, asking Buildbucket for the build status
[04:46:20.115] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:20.115] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:69:0) after 1m0s
[04:47:20.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:69:0)
[04:47:20.169] Timer tick, asking Buildbucket for the build status
[04:47:20.350] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:20.350] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:70:0) after 1m0s
[04:48:20.401] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:70:0)
[04:48:20.401] Timer tick, asking Buildbucket for the build status
[04:48:20.484] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:20.484] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:71:0) after 1m0s
[04:49:20.623] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:71:0)
[04:49:20.624] Timer tick, asking Buildbucket for the build status
[04:49:20.824] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:20.824] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:72:0) after 1m0s
[04:50:20.879] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:72:0)
[04:50:20.879] Timer tick, asking Buildbucket for the build status
[04:50:21.200] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:21.200] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:73:0) after 1m0s
[04:51:21.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:73:0)
[04:51:21.249] Timer tick, asking Buildbucket for the build status
[04:51:21.320] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:21.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:74:0) after 1m0s
[04:52:21.408] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:74:0)
[04:52:21.408] Timer tick, asking Buildbucket for the build status
[04:52:21.951] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:21.951] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:75:0) after 1m0s
[04:53:21.998] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:75:0)
[04:53:21.998] Timer tick, asking Buildbucket for the build status
[04:53:22.139] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:22.139] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:76:0) after 1m0s
[04:54:22.163] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:76:0)
[04:54:22.163] Timer tick, asking Buildbucket for the build status
[04:54:22.301] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:22.301] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:77:0) after 1m0s
[04:55:22.386] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:77:0)
[04:55:22.386] Timer tick, asking Buildbucket for the build status
[04:55:22.548] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:22.548] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:78:0) after 1m0s
[04:56:22.615] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:78:0)
[04:56:22.615] Timer tick, asking Buildbucket for the build status
[04:56:22.726] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:22.726] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:79:0) after 1m0s
[04:57:22.747] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:79:0)
[04:57:22.747] Timer tick, asking Buildbucket for the build status
[04:57:22.893] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:22.893] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:80:0) after 1m0s
[04:58:22.978] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:80:0)
[04:58:22.978] Timer tick, asking Buildbucket for the build status
[04:58:23.048] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:23.048] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:81:0) after 1m0s
[04:59:23.083] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:81:0)
[04:59:23.083] Timer tick, asking Buildbucket for the build status
[04:59:23.152] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:23.152] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:82:0) after 1m0s
[05:00:23.169] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:82:0)
[05:00:23.169] Timer tick, asking Buildbucket for the build status
[05:00:23.319] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:23.320] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:83:0) after 1m0s
[05:01:23.339] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:83:0)
[05:01:23.339] Timer tick, asking Buildbucket for the build status
[05:01:23.433] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:23.433] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:84:0) after 1m0s
[05:02:23.457] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:84:0)
[05:02:23.457] Timer tick, asking Buildbucket for the build status
[05:02:23.563] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:23.563] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:85:0) after 1m0s
[05:03:23.591] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:85:0)
[05:03:23.591] Timer tick, asking Buildbucket for the build status
[05:03:23.684] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:23.684] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:86:0) after 1m0s
[05:04:23.704] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:86:0)
[05:04:23.704] Timer tick, asking Buildbucket for the build status
[05:04:23.790] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:23.790] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:87:0) after 1m0s
[05:05:23.906] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:87:0)
[05:05:23.906] Timer tick, asking Buildbucket for the build status
[05:05:24.100] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:24.100] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:88:0) after 1m0s
[05:06:24.249] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:88:0)
[05:06:24.249] Timer tick, asking Buildbucket for the build status
[05:06:24.359] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:24.359] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:89:0) after 1m0s
[05:07:24.374] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:89:0)
[05:07:24.374] Timer tick, asking Buildbucket for the build status
[05:07:24.803] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:24.804] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:90:0) after 1m0s
[05:08:24.871] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:90:0)
[05:08:24.871] Timer tick, asking Buildbucket for the build status
[05:08:24.972] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:24.972] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:91:0) after 1m0s
[05:09:25.073] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:91:0)
[05:09:25.073] Timer tick, asking Buildbucket for the build status
[05:09:25.172] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:25.172] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:92:0) after 1m0s
[05:10:25.327] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:92:0)
[05:10:25.327] Timer tick, asking Buildbucket for the build status
[05:10:25.869] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:25.869] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:93:0) after 1m0s
[05:11:25.890] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:93:0)
[05:11:25.890] Timer tick, asking Buildbucket for the build status
[05:11:26.008] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:26.008] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:94:0) after 1m0s
[05:12:26.076] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:94:0)
[05:12:26.076] Timer tick, asking Buildbucket for the build status
[05:12:26.151] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:26.151] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:95:0) after 1m0s
[05:13:26.178] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:95:0)
[05:13:26.178] Timer tick, asking Buildbucket for the build status
[05:13:26.312] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:26.312] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:96:0) after 1m0s
[05:14:26.370] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:96:0)
[05:14:26.370] Timer tick, asking Buildbucket for the build status
[05:14:26.467] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:26.467] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:97:0) after 1m0s
[05:15:26.497] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:97:0)
[05:15:26.497] Timer tick, asking Buildbucket for the build status
[05:15:27.035] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:27.035] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:98:0) after 1m0s
[05:16:27.104] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:98:0)
[05:16:27.104] Timer tick, asking Buildbucket for the build status
[05:16:27.179] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:27.179] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:99:0) after 1m0s
[05:17:27.203] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:99:0)
[05:17:27.203] Timer tick, asking Buildbucket for the build status
[05:17:27.284] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:27.284] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:100:0) after 1m0s
[05:18:27.489] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:100:0)
[05:18:27.489] Timer tick, asking Buildbucket for the build status
[05:18:27.809] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:27.809] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:101:0) after 1m0s
[05:19:27.857] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:101:0)
[05:19:27.857] Timer tick, asking Buildbucket for the build status
[05:19:28.033] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:28.033] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:102:0) after 1m0s
[05:20:28.116] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:102:0)
[05:20:28.116] Timer tick, asking Buildbucket for the build status
[05:20:28.245] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:28.245] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:103:0) after 1m0s
[05:21:28.274] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:103:0)
[05:21:28.274] Timer tick, asking Buildbucket for the build status
[05:21:28.382] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:28.382] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:104:0) after 1m0s
[05:22:28.416] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:104:0)
[05:22:28.416] Timer tick, asking Buildbucket for the build status
[05:22:28.802] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:28.802] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:105:0) after 1m0s
[05:23:28.856] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:105:0)
[05:23:28.856] Timer tick, asking Buildbucket for the build status
[05:23:28.925] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:28.925] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:106:0) after 1m0s
[05:24:29.004] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:106:0)
[05:24:29.004] Timer tick, asking Buildbucket for the build status
[05:24:29.113] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:29.113] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:107:0) after 1m0s
[05:25:29.201] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:107:0)
[05:25:29.201] Timer tick, asking Buildbucket for the build status
[05:25:29.326] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:29.326] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:108:0) after 1m0s
[05:26:29.441] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:108:0)
[05:26:29.441] Timer tick, asking Buildbucket for the build status
[05:26:29.531] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:29.531] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:109:0) after 1m0s
[05:27:29.726] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:109:0)
[05:27:29.726] Timer tick, asking Buildbucket for the build status
[05:27:29.898] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:29.898] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:110:0) after 1m0s
[05:28:29.956] Handling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:110:0)
[05:28:29.956] Timer tick, asking Buildbucket for the build status
[05:28:30.074] Build 8864684806362262224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:30.074] Scheduling timer "check-buildbucket-build-status" (chromium/linux-chromeos-dbg:9030114868337191056:111:0) after 1m0s
[05:29:15.857] Received PubSub notification, asking Buildbucket for the build status
[05:29:15.920] Build 8864684806362262224: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:29:15.920] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604381354807316",
"created_by": "project:chromium",
"created_ts": "1604374799343300",
"id": "8864684806362262224",
"parameters_json": "{\"builder_name\": \"linux-chromeos-dbg\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62\"}}",
"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\": \"54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62\", \"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-203-bxm2\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.chromiumos\", \"buildername\": \"linux-chromeos-dbg\", \"buildnumber\": 21122, \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"71d2e1d23d49c3616209dbf4776fd720aaa11560\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62\", \"got_revision_cp\": \"refs/heads/master@{#823436}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"959f41988a18e8511dcde1d646d798de4ea6b59d\", \"got_v8_revision\": \"037ccdfb11c6a626d405939fc582f85d0a8bebb6\", \"got_v8_revision_cp\": \"refs/heads/8.8.218@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62\", \"swarm_hashes_refs/heads/master(at){#823436}_without_patch\": {\"absl_hardening_tests\": \"6bd5b0bf6d18de6809409d1040bef847810bf63b\", \"accessibility_unittests\": \"e1688b56d641a72e06569f5023e230d1c1c2e47c\", \"angle_unittests\": \"d87ba792d633bbbe5020b8a2f3e25e1a1b1c1f45\", \"app_list_unittests\": \"40bd1c1c1233d68e8835cd03ccfafb2e8210b545\", \"app_shell_unittests\": \"3623f658e420b0ba2567fa0f122340806133c355\", \"ash_unittests\": \"5d1bd7b55301e3285e6fb309e47503a203c68774\", \"aura_unittests\": \"96fd371d7fc093f383e562a0e583d78386710714\", \"base_unittests\": \"182add1c4d877c5bf5f6c8834aceb9dbd55558e6\", \"base_util_unittests\": \"0ef9f6b8b97ba10946854e33fdf427ee880a0f9b\", \"blink_common_unittests\": \"9795dfc2d0f6ad935d7785f0d88248ee8ade14e0\", \"blink_fuzzer_unittests\": \"7bf9549c19e6dcd146faa9d021b3ca779eab9baf\", \"blink_heap_unittests\": \"1e58ec192e2e2c21567382fb251e84e74110af25\", \"blink_platform_unittests\": \"ba88460ebad28e89a5cc1ac6af885c1d034be8bb\", \"blink_unittests\": \"dfe7f71ee848201b6af7380ac0a27e8f85e483d7\", \"boringssl_crypto_tests\": \"d8acacf818353fa9b527d26c67631709a29ea81f\", \"boringssl_ssl_tests\": \"fb4d69d36ef63d3731532034dce3d900a07331cb\", \"browser_tests\": \"41d1e880c62eab4c1e7a5faf3c18eaf7cb5b20ed\", \"capture_unittests\": \"decccc5b30ce87cd8ba9e972245e45dc4717d6e2\", \"cast_unittests\": \"c53b584d0d5cdc452b44c63321d83ebff28b55ef\", \"cc_unittests\": \"a0d3636d010f1cd7f0f81395652b20c17c724c3d\", \"chrome_app_unittests\": \"4d0571d29c1d680bddc917493607780b5cd2a1ed\", \"chromedriver_unittests\": \"e6e07d9d6ec60f26af116e3de317caaf1be3f2ff\", \"chromeos_components_unittests\": \"cd1944e95b9a9190c272640bc144399501ec7361\", \"chromeos_unittests\": \"9171d7ca7e31b8cdb439e19a18b811c091462b36\", \"components_browsertests\": \"8d42b716ae8afbd6b78331d06c25947a26c583a5\", \"components_unittests\": \"17e2740b7fbc75a45fa61bece472a2fd4be20c3c\", \"compositor_unittests\": \"45885a00aac679a22080ba77f839270a20224426\", \"content_browsertests\": \"d2df0f1142ec1a48e004aeb375319ad7b230a8e3\", \"content_unittests\": \"44e44c869b10f540398bb1480b0840a7c6852d32\", \"crypto_unittests\": \"7c4b6a088a2911f30428c26976ace0cc4eae29ea\", \"dbus_unittests\": \"d2c7e1c3a5cc46032cf60d08bfa8a3f0b8029647\", \"device_unittests\": \"82d72f52e805e533e4c2cace8bf9758db7dcb7c0\", \"display_unittests\": \"25a0f2ebc796c19c12d9a4bbd61da322663dd29b\", \"events_unittests\": \"982655ee4d2a367350a90fbc978d20650c471dae\", \"exo_unittests\": \"7ee6c3798d3da33305ce1ec1f59da35497e2a637\", \"extensions_browsertests\": \"f9ef975c437dc1bf545beb2a98fadcb9d4c67085\", \"extensions_unittests\": \"81ef1c5c867aded4ccc46b3234b57965df851d20\", \"filesystem_service_unittests\": \"c95be67448c12f4be2efda67f2511ee796e6e02e\", \"gcm_unit_tests\": \"55d6c7c502a514fa4c7364b58688b20824c9283c\", \"gfx_unittests\": \"61cc90ea54a111102ce55f7edc47b2024969eef4\", \"gin_unittests\": \"8b72104321bd15b8ac64a2d35b87c4f364a1f3bd\", \"gl_unittests_ozone\": \"a25edf10c4a8affcb7a003cc55ffb4602c816c90\", \"google_apis_unittests\": \"ea1c6d0fca210225cde16e7ea8f4bbcb8d6b6dad\", \"gpu_unittests\": \"48882fb015d34cfb403ffc78ec6b6c4e25df953e\", \"gwp_asan_unittests\": \"4a5013d4bee790e2c9215b5a7473d2cc5bc9b715\", \"interactive_ui_tests\": \"8dbf0bf57ee193b6b4426e7535c61bcb33360d23\", \"ipc_tests\": \"058c4735ea0065f5ac65438ace7e9951268b8b7d\", \"jingle_unittests\": \"31248231f65c65be08a0318c7e3d619ddd7587da\", \"keyboard_unittests\": \"1430397af12587d27b04aca73adc21ff42718ef5\", \"latency_unittests\": \"4876d56ec0548ed065c0dcae59768e36c555c6a4\", \"libjingle_xmpp_unittests\": \"5a55dedda4d927acb697eb77a56673378afe42c9\", \"liburlpattern_unittests\": \"2d3b1876bdd726981bd7af8cd5256e7021b3412d\", \"media_blink_unittests\": \"6f4389f18f74dc7ae0bb5d1ae5bd33057992ec35\", \"media_unittests\": \"9859a72c313486dfe7ed9a7d31b701b69c7f81a5\", \"message_center_unittests\": \"075a77091c231cd4fe24346dbf9bd02da2419f2f\", \"midi_unittests\": \"0ccc510cc4de3bd00bc969c2c6ccbfdf91151d8f\", \"mojo_core_unittests\": \"ad9167d1c184652b0c7a0283beeecdecfe72550f\", \"mojo_unittests\": \"3e1a2d2c29ea4df9586bb4959a1ee5d010c187f7\", \"nacl_helper_nonsfi_unittests\": \"92406005ebef7f7a3bb5a5465e900a0944433331\", \"nacl_loader_unittests\": \"c1601ce8cc946f50633f3b981c0d5ae45a214daa\", \"native_theme_unittests\": \"ff9f793dd96b680a61b48c0ba9e82f54b1d769ab\", \"net_unittests\": \"6092fcdf03ac7a1f6beeea868daed00e1bb99fcc\", \"ozone_gl_unittests\": \"e01578f7ed8ad5f68dc1fe9edfad017ee998800d\", \"ozone_unittests\": \"75a183d6c83646cf30eaab3ba6bdf30d9054832e\", \"ozone_x11_unittests\": \"4b2a005b98569820c6f1e3324b507b331804f1e7\", \"pdf_unittests\": \"46e4e3c0b9a27a47a5efde61e00d0ff38beccbb6\", \"perfetto_unittests\": \"a8431fcf62632475b27c3637c565904854ac697b\", \"ppapi_unittests\": \"559b972ef1b91077a84a77760e6bf8820dd5b39c\", \"printing_unittests\": \"9814c87f512eade71b9f8aea3c1fbb8059d52ec8\", \"remoting_unittests\": \"7157e39f96ea1a6eedd1019b29cc1dd1b47ab0cf\", \"sandbox_linux_unittests\": \"d324cdbec4f18145e869e0a26b44d95d9f2ac00a\", \"service_manager_unittests\": \"0f7ef244b3c70625d1374cec0e543aa968d38782\", \"services_unittests\": \"bdcf6fde464a58add86c4d921fd0f66bc818677d\", \"shell_dialogs_unittests\": \"03a78bb74f9dc2285872c7284c86dfd773c152e3\", \"skia_unittests\": \"76433b72e9835911cc5280969dfb0a1520d40742\", \"snapshot_unittests\": \"39e6daacb780302a2eb462fc3df11514c926b021\", \"sql_unittests\": \"ee4e32e10d1ddb51f573507b170d99e80bbb7872\", \"storage_unittests\": \"3ecbd88a5628a75b9beb2080e14dddb6481a26f8\", \"sync_integration_tests\": \"63c78c3323d1e82afa562850c40250f97679b676\", \"traffic_annotation_auditor_unittests\": \"22b3ecd7f93dab19a6c43eafd8c04e125396e531\", \"ui_base_unittests\": \"ecb2812f24fe991e1288ecbe36e99b94edecaebd\", \"ui_chromeos_unittests\": \"bf6e8b987eb9019ae22ac1cc7bc571614a88f5a1\", \"ui_touch_selection_unittests\": \"6fdb174216412e62b5a97adbe265eacf29ae55b7\", \"unit_tests\": \"4e2dd9fe3a365f7082083339d0bea152b5bf07b6\", \"url_unittests\": \"9eb0aecff6af73d434c543e8bf209d6778e14e9e\", \"usage_time_limit_unittests\": \"1ccc803806be2d9c833cf0d23cbc497a41aac97d\", \"views_unittests\": \"0c029379435909dfbcd65c3accf54d85949de672\", \"viz_unittests\": \"7e60a03c95a363730dc60acaec694b9f894891e6\", \"wm_unittests\": \"8a9c40d82b9690af076450052d7cd039b0d906b9\", \"wtf_unittests\": \"2c02316ff9c08f574403038db6be462eac7b34e3\", \"zlib_unittests\": \"9a342f6e90027907470e15b6f5992e230790093a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_4f0964162888fb5bae249a111360c6881d9714a6e065febf968887beea0fb93f_v2\", \"builder_7d75f6999c07e42a7f7fde2313383264fbd8ed871e2008bde33b278973c1f4c8_v2\", \"builder_84395cd86c4615c28c38755187d54d1bbe2dcea268d302d74191d04ce3a055d0_v2\", \"builder_8bff6729eb7a20b41fa723231755491227dfae6025a6d103df6a00cfb6cc22b8_v2\", \"builder_ebb06fb409ef3255b3561ad6bc0df7706a15849e85ba50b708bdfb917530d411_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-203-bxm2\"], \"image\": [\"chrome-xenial-20102500-5da31bca5f9\"], \"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\": [\"5614-d862eaa\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1604375043977024",
"status": "COMPLETED",
"status_changed_ts": "1604381355369813",
"tags": [
"build_address:luci.chromium.ci/linux-chromeos-dbg/21122",
"builder:linux-chromeos-dbg",
"buildset:commit/git/54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/54f4c4a5ddf2ad1c9420fb00efe17a0c62826c62",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030114868337191056",
"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/8864684806362262224/+/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:4fa5022f570e1210",
"user_agent:luci-scheduler"
],
"updated_ts": "1604381355374534",
"url": "https://ci.chromium.org/b/8864684806362262224",
"utcnow_ts": "1604381355912625"
}
}
[05:29:15.920] Invocation finished in 1h49m17.844560037s with status SUCCEEDED