[04:38:56.443] New invocation is queued and will start shortly
[04:38:57.515] Starting the invocation (attempt 1)
[04:38:57.553] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:38:57.553] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:38:57.554] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036724736654296912",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9036724736654296912",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2",
"gitiles_ref:refs/heads/master"
]
}
[04:38:58.350] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1598071137653076",
"id": "8871294674722886816",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598071138197492",
"tags": [
"build_address:luci.chromium.ci/win-official/392",
"builder:win-official",
"buildset:commit/git/6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036724736654296912",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8871294674722886816/+/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": "1598071138197633",
"url": "https://ci.chromium.org/b/8871294674722886816",
"utcnow_ts": "1598071138337708"
}
}
[04:38:58.350] Task URL: https://ci.chromium.org/b/8871294674722886816
[04:38:58.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:2:0) after 1m0s
[04:39:40.216] Received PubSub notification, asking Buildbucket for the build status
[04:39:40.452] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:58.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:2:0)
[04:39:58.371] Timer tick, asking Buildbucket for the build status
[04:39:58.504] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:58.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:4:0) after 1m0s
[04:40:58.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:4:0)
[04:40:58.542] Timer tick, asking Buildbucket for the build status
[04:40:58.641] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:58.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:5:0) after 1m0s
[04:41:58.665] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:5:0)
[04:41:58.665] Timer tick, asking Buildbucket for the build status
[04:41:58.773] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:58.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:6:0) after 1m0s
[04:42:58.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:6:0)
[04:42:58.821] Timer tick, asking Buildbucket for the build status
[04:42:58.918] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:58.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:7:0) after 1m0s
[04:43:58.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:7:0)
[04:43:58.929] Timer tick, asking Buildbucket for the build status
[04:43:59.185] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:59.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:8:0) after 1m0s
[04:44:59.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:8:0)
[04:44:59.208] Timer tick, asking Buildbucket for the build status
[04:44:59.404] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:59.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:9:0) after 1m0s
[04:45:59.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:9:0)
[04:45:59.428] Timer tick, asking Buildbucket for the build status
[04:45:59.524] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:59.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:10:0) after 1m0s
[04:46:59.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:10:0)
[04:46:59.543] Timer tick, asking Buildbucket for the build status
[04:46:59.666] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:59.666] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:11:0) after 1m0s
[04:47:59.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:11:0)
[04:47:59.666] Timer tick, asking Buildbucket for the build status
[04:47:59.841] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:59.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:12:0) after 1m0s
[04:48:59.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:12:0)
[04:48:59.905] Timer tick, asking Buildbucket for the build status
[04:49:00.135] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:00.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:13:0) after 1m0s
[04:50:00.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:13:0)
[04:50:00.182] Timer tick, asking Buildbucket for the build status
[04:50:00.445] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:00.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:14:0) after 1m0s
[04:51:01.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:14:0)
[04:51:01.717] Timer tick, asking Buildbucket for the build status
[04:51:01.843] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:01.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:15:0) after 1m0s
[04:52:01.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:15:0)
[04:52:01.864] Timer tick, asking Buildbucket for the build status
[04:52:02.245] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:02.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:16:0) after 1m0s
[04:53:02.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:16:0)
[04:53:02.258] Timer tick, asking Buildbucket for the build status
[04:53:02.495] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:02.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:17:0) after 1m0s
[04:54:02.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:17:0)
[04:54:02.509] Timer tick, asking Buildbucket for the build status
[04:54:02.827] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:02.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:18:0) after 1m0s
[04:55:02.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:18:0)
[04:55:02.849] Timer tick, asking Buildbucket for the build status
[04:55:03.018] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:03.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:19:0) after 1m0s
[04:56:03.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:19:0)
[04:56:03.065] Timer tick, asking Buildbucket for the build status
[04:56:03.243] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:03.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:20:0) after 1m0s
[04:57:03.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:20:0)
[04:57:03.264] Timer tick, asking Buildbucket for the build status
[04:57:03.615] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:03.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:21:0) after 1m0s
[04:58:03.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:21:0)
[04:58:03.700] Timer tick, asking Buildbucket for the build status
[04:58:03.885] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:03.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:22:0) after 1m0s
[04:59:03.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:22:0)
[04:59:03.963] Timer tick, asking Buildbucket for the build status
[04:59:04.182] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:04.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:23:0) after 1m0s
[05:00:04.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:23:0)
[05:00:04.328] Timer tick, asking Buildbucket for the build status
[05:00:04.478] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:04.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:24:0) after 1m0s
[05:01:04.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:24:0)
[05:01:04.703] Timer tick, asking Buildbucket for the build status
[05:01:04.826] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:04.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:25:0) after 1m0s
[05:02:05.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:25:0)
[05:02:05.031] Timer tick, asking Buildbucket for the build status
[05:02:06.453] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:06.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:26:0) after 1m0s
[05:03:06.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:26:0)
[05:03:06.503] Timer tick, asking Buildbucket for the build status
[05:03:06.631] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:06.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:27:0) after 1m0s
[05:04:06.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:27:0)
[05:04:06.705] Timer tick, asking Buildbucket for the build status
[05:04:06.908] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:06.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:28:0) after 1m0s
[05:05:06.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:28:0)
[05:05:06.914] Timer tick, asking Buildbucket for the build status
[05:05:07.012] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:07.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:29:0) after 1m0s
[05:06:07.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:29:0)
[05:06:07.072] Timer tick, asking Buildbucket for the build status
[05:06:07.529] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:07.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:30:0) after 1m0s
[05:07:07.598] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:30:0)
[05:07:07.598] Timer tick, asking Buildbucket for the build status
[05:07:07.698] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:07.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:31:0) after 1m0s
[05:08:07.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:31:0)
[05:08:07.731] Timer tick, asking Buildbucket for the build status
[05:08:07.930] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:07.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:32:0) after 1m0s
[05:09:07.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:32:0)
[05:09:07.971] Timer tick, asking Buildbucket for the build status
[05:09:08.242] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:08.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:33:0) after 1m0s
[05:10:08.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:33:0)
[05:10:08.312] Timer tick, asking Buildbucket for the build status
[05:10:08.464] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:08.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:34:0) after 1m0s
[05:11:08.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:34:0)
[05:11:08.482] Timer tick, asking Buildbucket for the build status
[05:11:08.615] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:08.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:35:0) after 1m0s
[05:12:08.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:35:0)
[05:12:08.673] Timer tick, asking Buildbucket for the build status
[05:12:08.786] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:08.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:36:0) after 1m0s
[05:13:08.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:36:0)
[05:13:08.829] Timer tick, asking Buildbucket for the build status
[05:13:09.081] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:09.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:37:0) after 1m0s
[05:14:09.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:37:0)
[05:14:09.186] Timer tick, asking Buildbucket for the build status
[05:14:09.304] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:09.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:38:0) after 1m0s
[05:15:09.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:38:0)
[05:15:09.280] Timer tick, asking Buildbucket for the build status
[05:15:09.552] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:09.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:39:0) after 1m0s
[05:16:09.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:39:0)
[05:16:09.608] Timer tick, asking Buildbucket for the build status
[05:16:09.774] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:09.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:40:0) after 1m0s
[05:17:09.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:40:0)
[05:17:09.839] Timer tick, asking Buildbucket for the build status
[05:17:09.946] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:09.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:41:0) after 1m0s
[05:18:09.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:41:0)
[05:18:09.974] Timer tick, asking Buildbucket for the build status
[05:18:10.036] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:10.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:42:0) after 1m0s
[05:19:10.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:42:0)
[05:19:10.080] Timer tick, asking Buildbucket for the build status
[05:19:10.192] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:10.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:43:0) after 1m0s
[05:20:10.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:43:0)
[05:20:10.216] Timer tick, asking Buildbucket for the build status
[05:20:10.368] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:10.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:44:0) after 1m0s
[05:21:10.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:44:0)
[05:21:10.739] Timer tick, asking Buildbucket for the build status
[05:21:10.812] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:10.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:45:0) after 1m0s
[05:22:10.986] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:45:0)
[05:22:10.986] Timer tick, asking Buildbucket for the build status
[05:22:11.064] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:11.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:46:0) after 1m0s
[05:23:11.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:46:0)
[05:23:11.168] Timer tick, asking Buildbucket for the build status
[05:23:11.422] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:11.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:47:0) after 1m0s
[05:24:11.446] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:47:0)
[05:24:11.446] Timer tick, asking Buildbucket for the build status
[05:24:11.932] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:11.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:48:0) after 1m0s
[05:25:11.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:48:0)
[05:25:11.960] Timer tick, asking Buildbucket for the build status
[05:25:12.031] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:12.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:49:0) after 1m0s
[05:26:12.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:49:0)
[05:26:12.230] Timer tick, asking Buildbucket for the build status
[05:26:15.387] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:15.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:50:0) after 1m0s
[05:27:15.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:50:0)
[05:27:15.402] Timer tick, asking Buildbucket for the build status
[05:27:15.805] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:15.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:51:0) after 1m0s
[05:28:16.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:51:0)
[05:28:16.033] Timer tick, asking Buildbucket for the build status
[05:28:16.519] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:16.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:52:0) after 1m0s
[05:29:16.668] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:52:0)
[05:29:16.668] Timer tick, asking Buildbucket for the build status
[05:29:17.045] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:17.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:53:0) after 1m0s
[05:30:17.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:53:0)
[05:30:17.077] Timer tick, asking Buildbucket for the build status
[05:30:17.250] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:17.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:54:0) after 1m0s
[05:31:17.322] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:54:0)
[05:31:17.322] Timer tick, asking Buildbucket for the build status
[05:31:17.705] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:17.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:55:0) after 1m0s
[05:32:17.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:55:0)
[05:32:17.785] Timer tick, asking Buildbucket for the build status
[05:32:18.464] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:18.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:56:0) after 1m0s
[05:33:18.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:56:0)
[05:33:18.535] Timer tick, asking Buildbucket for the build status
[05:33:18.721] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:18.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:57:0) after 1m0s
[05:34:18.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:57:0)
[05:34:18.953] Timer tick, asking Buildbucket for the build status
[05:34:19.272] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:19.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:58:0) after 1m0s
[05:35:19.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:58:0)
[05:35:19.306] Timer tick, asking Buildbucket for the build status
[05:35:19.547] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:19.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:59:0) after 1m0s
[05:36:19.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:59:0)
[05:36:19.644] Timer tick, asking Buildbucket for the build status
[05:36:19.799] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:19.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:60:0) after 1m0s
[05:37:19.860] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:60:0)
[05:37:19.860] Timer tick, asking Buildbucket for the build status
[05:37:20.198] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:20.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:61:0) after 1m0s
[05:38:20.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:61:0)
[05:38:20.339] Timer tick, asking Buildbucket for the build status
[05:38:20.432] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:20.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:62:0) after 1m0s
[05:39:20.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:62:0)
[05:39:20.498] Timer tick, asking Buildbucket for the build status
[05:39:20.752] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:20.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:63:0) after 1m0s
[05:40:20.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:63:0)
[05:40:20.789] Timer tick, asking Buildbucket for the build status
[05:40:20.910] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:20.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:64:0) after 1m0s
[05:41:20.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:64:0)
[05:41:20.944] Timer tick, asking Buildbucket for the build status
[05:41:21.083] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:21.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:65:0) after 1m0s
[05:42:21.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:65:0)
[05:42:21.111] Timer tick, asking Buildbucket for the build status
[05:42:21.350] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:21.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:66:0) after 1m0s
[05:43:21.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:66:0)
[05:43:21.404] Timer tick, asking Buildbucket for the build status
[05:43:21.795] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:21.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:67:0) after 1m0s
[05:44:21.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:67:0)
[05:44:21.855] Timer tick, asking Buildbucket for the build status
[05:44:22.040] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:22.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:68:0) after 1m0s
[05:45:22.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:68:0)
[05:45:22.066] Timer tick, asking Buildbucket for the build status
[05:45:22.295] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:22.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:69:0) after 1m0s
[05:46:22.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:69:0)
[05:46:22.330] Timer tick, asking Buildbucket for the build status
[05:46:22.523] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:22.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:70:0) after 1m0s
[05:47:22.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:70:0)
[05:47:22.537] Timer tick, asking Buildbucket for the build status
[05:47:23.080] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:23.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:71:0) after 1m0s
[05:48:23.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:71:0)
[05:48:23.105] Timer tick, asking Buildbucket for the build status
[05:48:23.517] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:23.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:72:0) after 1m0s
[05:49:23.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:72:0)
[05:49:23.543] Timer tick, asking Buildbucket for the build status
[05:49:23.972] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:23.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:73:0) after 1m0s
[05:50:24.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:73:0)
[05:50:24.002] Timer tick, asking Buildbucket for the build status
[05:50:24.098] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:24.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:74:0) after 1m0s
[05:51:24.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:74:0)
[05:51:24.147] Timer tick, asking Buildbucket for the build status
[05:51:24.282] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:24.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:75:0) after 1m0s
[05:52:24.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:75:0)
[05:52:24.317] Timer tick, asking Buildbucket for the build status
[05:52:25.865] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:25.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:76:0) after 1m0s
[05:53:25.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:76:0)
[05:53:25.890] Timer tick, asking Buildbucket for the build status
[05:53:26.035] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:26.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:77:0) after 1m0s
[05:54:26.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:77:0)
[05:54:26.075] Timer tick, asking Buildbucket for the build status
[05:54:27.253] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:27.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:78:0) after 1m0s
[05:55:27.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:78:0)
[05:55:27.314] Timer tick, asking Buildbucket for the build status
[05:55:27.413] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:27.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:79:0) after 1m0s
[05:56:27.495] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:79:0)
[05:56:27.495] Timer tick, asking Buildbucket for the build status
[05:56:27.560] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:27.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:80:0) after 1m0s
[05:57:27.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:80:0)
[05:57:27.627] Timer tick, asking Buildbucket for the build status
[05:57:27.730] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:27.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:81:0) after 1m0s
[05:58:27.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:81:0)
[05:58:27.756] Timer tick, asking Buildbucket for the build status
[05:58:27.907] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:27.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:82:0) after 1m0s
[05:59:27.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:82:0)
[05:59:27.941] Timer tick, asking Buildbucket for the build status
[05:59:28.070] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:28.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:83:0) after 1m0s
[06:00:28.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:83:0)
[06:00:28.107] Timer tick, asking Buildbucket for the build status
[06:00:28.209] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:28.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:84:0) after 1m0s
[06:01:28.226] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:84:0)
[06:01:28.226] Timer tick, asking Buildbucket for the build status
[06:01:28.295] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:28.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:85:0) after 1m0s
[06:02:28.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:85:0)
[06:02:28.330] Timer tick, asking Buildbucket for the build status
[06:02:28.459] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:28.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:86:0) after 1m0s
[06:03:28.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:86:0)
[06:03:28.497] Timer tick, asking Buildbucket for the build status
[06:03:28.601] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:28.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:87:0) after 1m0s
[06:04:28.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:87:0)
[06:04:28.645] Timer tick, asking Buildbucket for the build status
[06:04:51.396] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:51.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:88:0) after 1m0s
[06:05:51.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:88:0)
[06:05:51.501] Timer tick, asking Buildbucket for the build status
[06:05:51.730] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:51.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:89:0) after 1m0s
[06:06:51.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:89:0)
[06:06:51.809] Timer tick, asking Buildbucket for the build status
[06:06:51.929] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:51.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:90:0) after 1m0s
[06:07:51.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:90:0)
[06:07:51.958] Timer tick, asking Buildbucket for the build status
[06:07:52.039] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:52.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:91:0) after 1m0s
[06:08:52.067] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:91:0)
[06:08:52.067] Timer tick, asking Buildbucket for the build status
[06:08:52.419] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:52.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:92:0) after 1m0s
[06:09:54.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:92:0)
[06:09:54.459] Timer tick, asking Buildbucket for the build status
[06:10:07.451] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:07.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:93:0) after 1m0s
[06:11:07.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:93:0)
[06:11:07.464] Timer tick, asking Buildbucket for the build status
[06:11:07.564] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:07.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:94:0) after 1m0s
[06:12:07.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:94:0)
[06:12:07.688] Timer tick, asking Buildbucket for the build status
[06:12:07.783] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:07.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:95:0) after 1m0s
[06:13:07.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:95:0)
[06:13:07.848] Timer tick, asking Buildbucket for the build status
[06:13:08.195] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:08.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:96:0) after 1m0s
[06:14:08.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:96:0)
[06:14:08.227] Timer tick, asking Buildbucket for the build status
[06:14:08.372] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:08.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:97:0) after 1m0s
[06:15:08.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:97:0)
[06:15:08.496] Timer tick, asking Buildbucket for the build status
[06:15:08.688] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:08.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:98:0) after 1m0s
[06:16:08.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:98:0)
[06:16:08.787] Timer tick, asking Buildbucket for the build status
[06:16:08.860] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:08.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:99:0) after 1m0s
[06:17:08.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:99:0)
[06:17:08.882] Timer tick, asking Buildbucket for the build status
[06:17:08.939] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:08.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:100:0) after 1m0s
[06:18:08.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:100:0)
[06:18:08.993] Timer tick, asking Buildbucket for the build status
[06:18:09.228] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:09.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:101:0) after 1m0s
[06:19:09.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:101:0)
[06:19:09.306] Timer tick, asking Buildbucket for the build status
[06:19:09.910] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:09.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:102:0) after 1m0s
[06:20:09.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:102:0)
[06:20:09.939] Timer tick, asking Buildbucket for the build status
[06:20:10.062] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:10.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:103:0) after 1m0s
[06:21:10.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:103:0)
[06:21:10.127] Timer tick, asking Buildbucket for the build status
[06:21:12.692] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:12.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:104:0) after 1m0s
[06:22:12.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:104:0)
[06:22:12.794] Timer tick, asking Buildbucket for the build status
[06:22:12.956] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:12.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:105:0) after 1m0s
[06:23:12.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:105:0)
[06:23:12.992] Timer tick, asking Buildbucket for the build status
[06:23:13.130] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:13.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:106:0) after 1m0s
[06:24:13.170] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:106:0)
[06:24:13.170] Timer tick, asking Buildbucket for the build status
[06:24:13.318] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:13.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:107:0) after 1m0s
[06:25:13.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:107:0)
[06:25:13.351] Timer tick, asking Buildbucket for the build status
[06:25:13.450] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:13.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:108:0) after 1m0s
[06:26:13.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:108:0)
[06:26:13.439] Timer tick, asking Buildbucket for the build status
[06:26:16.178] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:16.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:109:0) after 1m0s
[06:27:16.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:109:0)
[06:27:16.262] Timer tick, asking Buildbucket for the build status
[06:27:16.767] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:16.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:110:0) after 1m0s
[06:28:16.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:110:0)
[06:28:16.826] Timer tick, asking Buildbucket for the build status
[06:28:16.953] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:16.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:111:0) after 1m0s
[06:29:16.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:111:0)
[06:29:16.988] Timer tick, asking Buildbucket for the build status
[06:29:17.273] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:17.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:112:0) after 1m0s
[06:30:17.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:112:0)
[06:30:17.316] Timer tick, asking Buildbucket for the build status
[06:30:17.535] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:17.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:113:0) after 1m0s
[06:31:17.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:113:0)
[06:31:17.567] Timer tick, asking Buildbucket for the build status
[06:31:18.734] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:18.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:114:0) after 1m0s
[06:32:18.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:114:0)
[06:32:18.870] Timer tick, asking Buildbucket for the build status
[06:32:19.030] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:19.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:115:0) after 1m0s
[06:33:19.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:115:0)
[06:33:19.068] Timer tick, asking Buildbucket for the build status
[06:33:19.170] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:19.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:116:0) after 1m0s
[06:34:19.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:116:0)
[06:34:19.196] Timer tick, asking Buildbucket for the build status
[06:34:19.387] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:19.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:117:0) after 1m0s
[06:35:19.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:117:0)
[06:35:19.418] Timer tick, asking Buildbucket for the build status
[06:35:19.604] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:19.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:118:0) after 1m0s
[06:36:19.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:118:0)
[06:36:19.650] Timer tick, asking Buildbucket for the build status
[06:36:20.184] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:20.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:119:0) after 1m0s
[06:37:20.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:119:0)
[06:37:20.277] Timer tick, asking Buildbucket for the build status
[06:37:20.690] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:20.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:120:0) after 1m0s
[06:38:20.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:120:0)
[06:38:20.680] Timer tick, asking Buildbucket for the build status
[06:38:20.793] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:20.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:121:0) after 1m0s
[06:39:20.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:121:0)
[06:39:20.843] Timer tick, asking Buildbucket for the build status
[06:39:21.852] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:21.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:122:0) after 1m0s
[06:40:21.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:122:0)
[06:40:21.965] Timer tick, asking Buildbucket for the build status
[06:40:22.076] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:22.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:123:0) after 1m0s
[06:41:22.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:123:0)
[06:41:22.125] Timer tick, asking Buildbucket for the build status
[06:41:22.388] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:22.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:124:0) after 1m0s
[06:42:22.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:124:0)
[06:42:22.448] Timer tick, asking Buildbucket for the build status
[06:42:22.566] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:22.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:125:0) after 1m0s
[06:43:22.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:125:0)
[06:43:22.607] Timer tick, asking Buildbucket for the build status
[06:43:23.619] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:23.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:126:0) after 1m0s
[06:44:23.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:126:0)
[06:44:23.682] Timer tick, asking Buildbucket for the build status
[06:44:24.035] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:24.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:127:0) after 1m0s
[06:45:24.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:127:0)
[06:45:24.112] Timer tick, asking Buildbucket for the build status
[06:45:24.206] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:24.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:128:0) after 1m0s
[06:46:24.333] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:128:0)
[06:46:24.333] Timer tick, asking Buildbucket for the build status
[06:46:24.576] Build 8871294674722886816: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:24.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036724736654296912:129:0) after 1m0s
[06:46:56.450] Received PubSub notification, asking Buildbucket for the build status
[06:46:56.517] Build 8871294674722886816: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:46:56.517] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1598078815648026",
"created_by": "project:chromium",
"created_ts": "1598071137653076",
"id": "8871294674722886816",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-7-oles\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 392, \"got_angle_revision\": \"682f9141d174fb5dedb4d61de0ce854784a36cfb\", \"got_dawn_revision\": \"e8dc820fbc1e28eba80b8e8c49bb3e566874f4e7\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2\", \"got_revision_cp\": \"refs/heads/master@{#800815}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"622558b02e1a5441c579994831a6338ffaf2de5c\", \"got_v8_revision\": \"1ca342524984084e1352ca5dd07aa9b539ff7768\", \"got_v8_revision_cp\": \"refs/heads/8.7.3@{#1}\", \"got_webrtc_revision\": \"fbb49b4f7f86487846170498dbd13fc704cff5ec\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31979}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-7-oles\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1598071179429098",
"status": "COMPLETED",
"status_changed_ts": "1598078816057945",
"tags": [
"build_address:luci.chromium.ci/win-official/392",
"builder:win-official",
"buildset:commit/git/6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6726bfc99c158125a4dccc51bdcf7dc0f3d2d2f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036724736654296912",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8871294674722886816/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows",
"swarming_tag:recipe_name:chromium",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:4e2d47f0e4f33810",
"user_agent:luci-scheduler"
],
"updated_ts": "1598078816111547",
"url": "https://ci.chromium.org/b/8871294674722886816",
"utcnow_ts": "1598078816509426"
}
}
[06:46:56.517] Invocation finished in 2h8m0.090210701s with status SUCCEEDED