[15:05:20.284] New invocation is queued and will start shortly
[15:05:21.394] Starting the invocation (attempt 1)
[15:05:21.443] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:05:21.443] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:05:21.443] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030887121293775616",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"66feb3c37aaa1dfef1691d73396f187a641db67f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9030887121293775616",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/66feb3c37aaa1dfef1691d73396f187a641db67f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66feb3c37aaa1dfef1691d73396f187a641db67f",
"gitiles_ref:refs/heads/master"
]
}
[15:05:22.409] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603638321550256",
"id": "8865457059300362688",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"66feb3c37aaa1dfef1691d73396f187a641db67f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603638322128444",
"tags": [
"build_address:luci.chromium.ci/win-official/892",
"builder:win-official",
"buildset:commit/git/66feb3c37aaa1dfef1691d73396f187a641db67f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66feb3c37aaa1dfef1691d73396f187a641db67f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030887121293775616",
"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/8865457059300362688/+/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": "1603638322128723",
"url": "https://ci.chromium.org/b/8865457059300362688",
"utcnow_ts": "1603638322389162"
}
}
[15:05:22.409] Task URL: https://ci.chromium.org/b/8865457059300362688
[15:05:22.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:2:0) after 1m0s
[15:06:06.724] Received PubSub notification, asking Buildbucket for the build status
[15:06:06.791] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:22.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:2:0)
[15:06:22.437] Timer tick, asking Buildbucket for the build status
[15:06:22.498] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:22.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:4:0) after 1m0s
[15:07:22.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:4:0)
[15:07:22.517] Timer tick, asking Buildbucket for the build status
[15:07:22.710] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:22.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:5:0) after 1m0s
[15:08:22.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:5:0)
[15:08:22.730] Timer tick, asking Buildbucket for the build status
[15:08:22.795] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:22.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:6:0) after 1m0s
[15:09:22.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:6:0)
[15:09:22.819] Timer tick, asking Buildbucket for the build status
[15:09:22.888] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:22.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:7:0) after 1m0s
[15:10:22.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:7:0)
[15:10:22.910] Timer tick, asking Buildbucket for the build status
[15:10:23.003] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:23.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:8:0) after 1m0s
[15:11:23.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:8:0)
[15:11:23.033] Timer tick, asking Buildbucket for the build status
[15:11:23.115] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:23.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:9:0) after 1m0s
[15:12:23.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:9:0)
[15:12:23.155] Timer tick, asking Buildbucket for the build status
[15:12:23.399] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:23.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:10:0) after 1m0s
[15:13:23.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:10:0)
[15:13:23.425] Timer tick, asking Buildbucket for the build status
[15:13:23.547] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:23.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:11:0) after 1m0s
[15:14:23.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:11:0)
[15:14:23.565] Timer tick, asking Buildbucket for the build status
[15:14:23.641] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:23.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:12:0) after 1m0s
[15:15:23.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:12:0)
[15:15:23.664] Timer tick, asking Buildbucket for the build status
[15:15:23.757] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:23.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:13:0) after 1m0s
[15:16:23.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:13:0)
[15:16:23.794] Timer tick, asking Buildbucket for the build status
[15:16:23.951] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:23.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:14:0) after 1m0s
[15:17:23.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:14:0)
[15:17:23.924] Timer tick, asking Buildbucket for the build status
[15:17:23.989] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:23.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:15:0) after 1m0s
[15:18:24.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:15:0)
[15:18:24.016] Timer tick, asking Buildbucket for the build status
[15:18:24.484] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:24.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:16:0) after 1m0s
[15:19:24.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:16:0)
[15:19:24.518] Timer tick, asking Buildbucket for the build status
[15:19:24.591] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:24.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:17:0) after 1m0s
[15:20:24.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:17:0)
[15:20:24.664] Timer tick, asking Buildbucket for the build status
[15:20:24.817] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:24.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:18:0) after 1m0s
[15:21:24.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:18:0)
[15:21:24.839] Timer tick, asking Buildbucket for the build status
[15:21:24.902] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:24.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:19:0) after 1m0s
[15:22:24.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:19:0)
[15:22:24.927] Timer tick, asking Buildbucket for the build status
[15:22:24.991] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:24.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:20:0) after 1m0s
[15:23:25.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:20:0)
[15:23:25.015] Timer tick, asking Buildbucket for the build status
[15:23:25.145] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:25.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:21:0) after 1m0s
[15:24:25.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:21:0)
[15:24:25.192] Timer tick, asking Buildbucket for the build status
[15:24:25.263] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:25.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:22:0) after 1m0s
[15:25:25.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:22:0)
[15:25:25.287] Timer tick, asking Buildbucket for the build status
[15:25:25.393] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:25.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:23:0) after 1m0s
[15:26:25.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:23:0)
[15:26:25.415] Timer tick, asking Buildbucket for the build status
[15:26:25.518] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:25.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:24:0) after 1m0s
[15:27:25.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:24:0)
[15:27:25.524] Timer tick, asking Buildbucket for the build status
[15:27:25.678] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:25.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:25:0) after 1m0s
[15:28:25.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:25:0)
[15:28:25.698] Timer tick, asking Buildbucket for the build status
[15:28:25.833] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:25.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:26:0) after 1m0s
[15:29:25.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:26:0)
[15:29:25.844] Timer tick, asking Buildbucket for the build status
[15:29:26.024] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:26.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:27:0) after 1m0s
[15:30:26.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:27:0)
[15:30:26.053] Timer tick, asking Buildbucket for the build status
[15:30:26.169] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:26.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:28:0) after 1m0s
[15:31:26.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:28:0)
[15:31:26.195] Timer tick, asking Buildbucket for the build status
[15:31:26.356] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:26.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:29:0) after 1m0s
[15:32:26.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:29:0)
[15:32:26.392] Timer tick, asking Buildbucket for the build status
[15:32:26.463] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:26.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:30:0) after 1m0s
[15:33:26.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:30:0)
[15:33:26.485] Timer tick, asking Buildbucket for the build status
[15:33:26.563] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:26.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:31:0) after 1m0s
[15:34:26.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:31:0)
[15:34:26.585] Timer tick, asking Buildbucket for the build status
[15:34:26.821] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:26.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:32:0) after 1m0s
[15:35:26.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:32:0)
[15:35:26.875] Timer tick, asking Buildbucket for the build status
[15:35:27.434] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:27.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:33:0) after 1m0s
[15:36:27.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:33:0)
[15:36:27.465] Timer tick, asking Buildbucket for the build status
[15:36:27.536] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:27.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:34:0) after 1m0s
[15:37:27.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:34:0)
[15:37:27.560] Timer tick, asking Buildbucket for the build status
[15:37:27.715] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:27.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:35:0) after 1m0s
[15:38:27.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:35:0)
[15:38:27.764] Timer tick, asking Buildbucket for the build status
[15:38:28.021] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:28.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:36:0) after 1m0s
[15:39:28.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:36:0)
[15:39:28.044] Timer tick, asking Buildbucket for the build status
[15:39:28.162] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:28.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:37:0) after 1m0s
[15:40:28.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:37:0)
[15:40:28.199] Timer tick, asking Buildbucket for the build status
[15:40:28.381] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:28.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:38:0) after 1m0s
[15:41:28.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:38:0)
[15:41:28.407] Timer tick, asking Buildbucket for the build status
[15:41:28.580] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:28.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:39:0) after 1m0s
[15:42:28.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:39:0)
[15:42:28.600] Timer tick, asking Buildbucket for the build status
[15:42:28.863] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:28.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:40:0) after 1m0s
[15:43:28.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:40:0)
[15:43:28.937] Timer tick, asking Buildbucket for the build status
[15:43:29.000] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:29.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:41:0) after 1m0s
[15:44:29.004] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:41:0)
[15:44:29.004] Timer tick, asking Buildbucket for the build status
[15:44:29.306] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:29.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:42:0) after 1m0s
[15:45:29.329] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:42:0)
[15:45:29.329] Timer tick, asking Buildbucket for the build status
[15:45:29.584] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:29.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:43:0) after 1m0s
[15:46:29.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:43:0)
[15:46:29.608] Timer tick, asking Buildbucket for the build status
[15:46:29.713] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:29.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:44:0) after 1m0s
[15:47:29.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:44:0)
[15:47:29.744] Timer tick, asking Buildbucket for the build status
[15:47:29.810] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:29.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:45:0) after 1m0s
[15:48:29.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:45:0)
[15:48:29.856] Timer tick, asking Buildbucket for the build status
[15:48:29.923] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:29.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:46:0) after 1m0s
[15:49:29.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:46:0)
[15:49:29.946] Timer tick, asking Buildbucket for the build status
[15:49:30.048] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:30.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:47:0) after 1m0s
[15:50:30.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:47:0)
[15:50:30.146] Timer tick, asking Buildbucket for the build status
[15:50:30.276] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:30.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:48:0) after 1m0s
[15:51:30.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:48:0)
[15:51:30.295] Timer tick, asking Buildbucket for the build status
[15:51:30.493] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:30.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:49:0) after 1m0s
[15:52:30.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:49:0)
[15:52:30.513] Timer tick, asking Buildbucket for the build status
[15:52:30.607] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:30.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:50:0) after 1m0s
[15:53:30.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:50:0)
[15:53:30.633] Timer tick, asking Buildbucket for the build status
[15:53:30.697] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:30.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:51:0) after 1m0s
[15:54:30.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:51:0)
[15:54:30.787] Timer tick, asking Buildbucket for the build status
[15:54:30.864] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:30.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:52:0) after 1m0s
[15:55:30.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:52:0)
[15:55:30.902] Timer tick, asking Buildbucket for the build status
[15:55:31.032] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:31.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:53:0) after 1m0s
[15:56:31.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:53:0)
[15:56:31.069] Timer tick, asking Buildbucket for the build status
[15:56:31.138] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:31.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:54:0) after 1m0s
[15:57:31.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:54:0)
[15:57:31.193] Timer tick, asking Buildbucket for the build status
[15:57:31.267] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:31.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:55:0) after 1m0s
[15:58:31.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:55:0)
[15:58:31.313] Timer tick, asking Buildbucket for the build status
[15:58:31.377] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:31.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:56:0) after 1m0s
[15:59:31.398] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:56:0)
[15:59:31.398] Timer tick, asking Buildbucket for the build status
[15:59:31.461] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:31.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:57:0) after 1m0s
[16:00:31.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:57:0)
[16:00:31.508] Timer tick, asking Buildbucket for the build status
[16:00:31.603] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:31.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:58:0) after 1m0s
[16:01:31.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:58:0)
[16:01:31.712] Timer tick, asking Buildbucket for the build status
[16:01:31.766] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:31.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:59:0) after 1m0s
[16:02:31.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:59:0)
[16:02:31.896] Timer tick, asking Buildbucket for the build status
[16:02:32.000] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:32.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:60:0) after 1m0s
[16:03:32.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:60:0)
[16:03:32.021] Timer tick, asking Buildbucket for the build status
[16:03:32.088] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:32.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:61:0) after 1m0s
[16:04:32.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:61:0)
[16:04:32.111] Timer tick, asking Buildbucket for the build status
[16:04:32.174] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:32.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:62:0) after 1m0s
[16:05:32.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:62:0)
[16:05:32.290] Timer tick, asking Buildbucket for the build status
[16:05:32.395] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:32.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:63:0) after 1m0s
[16:06:32.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:63:0)
[16:06:32.522] Timer tick, asking Buildbucket for the build status
[16:06:32.727] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:32.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:64:0) after 1m0s
[16:07:32.771] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:64:0)
[16:07:32.771] Timer tick, asking Buildbucket for the build status
[16:07:32.850] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:32.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:65:0) after 1m0s
[16:08:32.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:65:0)
[16:08:32.949] Timer tick, asking Buildbucket for the build status
[16:08:33.110] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:33.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:66:0) after 1m0s
[16:09:33.103] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:66:0)
[16:09:33.103] Timer tick, asking Buildbucket for the build status
[16:09:33.165] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:33.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:67:0) after 1m0s
[16:10:33.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:67:0)
[16:10:33.237] Timer tick, asking Buildbucket for the build status
[16:10:33.358] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:33.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:68:0) after 1m0s
[16:11:33.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:68:0)
[16:11:33.383] Timer tick, asking Buildbucket for the build status
[16:11:33.795] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:33.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:69:0) after 1m0s
[16:12:33.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:69:0)
[16:12:33.942] Timer tick, asking Buildbucket for the build status
[16:12:33.997] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:33.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:70:0) after 1m0s
[16:13:34.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:70:0)
[16:13:34.108] Timer tick, asking Buildbucket for the build status
[16:13:34.170] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:34.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:71:0) after 1m0s
[16:14:34.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:71:0)
[16:14:34.195] Timer tick, asking Buildbucket for the build status
[16:14:34.259] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:34.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:72:0) after 1m0s
[16:15:34.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:72:0)
[16:15:34.335] Timer tick, asking Buildbucket for the build status
[16:15:34.883] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:34.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:73:0) after 1m0s
[16:16:34.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:73:0)
[16:16:34.921] Timer tick, asking Buildbucket for the build status
[16:16:35.053] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:35.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:74:0) after 1m0s
[16:17:35.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:74:0)
[16:17:35.081] Timer tick, asking Buildbucket for the build status
[16:17:35.144] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:35.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:75:0) after 1m0s
[16:18:35.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:75:0)
[16:18:35.556] Timer tick, asking Buildbucket for the build status
[16:18:36.125] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:36.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:76:0) after 1m0s
[16:19:36.129] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:76:0)
[16:19:36.129] Timer tick, asking Buildbucket for the build status
[16:19:36.196] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:36.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:77:0) after 1m0s
[16:20:36.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:77:0)
[16:20:36.316] Timer tick, asking Buildbucket for the build status
[16:20:36.721] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:36.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:78:0) after 1m0s
[16:21:36.967] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:78:0)
[16:21:36.967] Timer tick, asking Buildbucket for the build status
[16:21:37.401] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:37.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:79:0) after 1m0s
[16:22:37.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:79:0)
[16:22:37.637] Timer tick, asking Buildbucket for the build status
[16:22:37.700] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:37.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:80:0) after 1m0s
[16:23:37.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:80:0)
[16:23:37.926] Timer tick, asking Buildbucket for the build status
[16:23:37.987] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:37.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:81:0) after 1m0s
[16:24:38.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:81:0)
[16:24:38.111] Timer tick, asking Buildbucket for the build status
[16:24:38.168] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:38.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:82:0) after 1m0s
[16:25:38.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:82:0)
[16:25:38.261] Timer tick, asking Buildbucket for the build status
[16:25:38.361] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:38.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:83:0) after 1m0s
[16:26:38.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:83:0)
[16:26:38.515] Timer tick, asking Buildbucket for the build status
[16:26:38.581] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:38.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:84:0) after 1m0s
[16:27:38.740] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:84:0)
[16:27:38.740] Timer tick, asking Buildbucket for the build status
[16:27:39.725] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:39.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:85:0) after 1m0s
[16:28:39.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:85:0)
[16:28:39.757] Timer tick, asking Buildbucket for the build status
[16:28:39.860] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:39.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:86:0) after 1m0s
[16:29:39.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:86:0)
[16:29:39.881] Timer tick, asking Buildbucket for the build status
[16:29:39.940] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:39.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:87:0) after 1m0s
[16:30:39.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:87:0)
[16:30:39.969] Timer tick, asking Buildbucket for the build status
[16:30:40.073] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:40.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:88:0) after 1m0s
[16:31:40.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:88:0)
[16:31:40.102] Timer tick, asking Buildbucket for the build status
[16:31:40.179] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:40.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:89:0) after 1m0s
[16:32:40.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:89:0)
[16:32:40.315] Timer tick, asking Buildbucket for the build status
[16:32:40.382] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:40.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:90:0) after 1m0s
[16:33:40.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:90:0)
[16:33:40.474] Timer tick, asking Buildbucket for the build status
[16:33:40.535] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:40.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:91:0) after 1m0s
[16:34:40.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:91:0)
[16:34:40.730] Timer tick, asking Buildbucket for the build status
[16:34:40.794] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:40.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:92:0) after 1m0s
[16:35:40.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:92:0)
[16:35:40.871] Timer tick, asking Buildbucket for the build status
[16:35:40.960] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:40.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:93:0) after 1m0s
[16:36:41.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:93:0)
[16:36:41.086] Timer tick, asking Buildbucket for the build status
[16:36:41.384] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:41.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:94:0) after 1m0s
[16:37:41.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:94:0)
[16:37:41.414] Timer tick, asking Buildbucket for the build status
[16:37:41.809] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:41.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:95:0) after 1m0s
[16:38:41.834] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:95:0)
[16:38:41.834] Timer tick, asking Buildbucket for the build status
[16:38:41.897] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:41.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:96:0) after 1m0s
[16:39:41.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:96:0)
[16:39:41.927] Timer tick, asking Buildbucket for the build status
[16:39:42.075] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:42.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:97:0) after 1m0s
[16:40:42.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:97:0)
[16:40:42.095] Timer tick, asking Buildbucket for the build status
[16:40:42.207] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:42.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:98:0) after 1m0s
[16:41:42.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:98:0)
[16:41:42.231] Timer tick, asking Buildbucket for the build status
[16:41:42.299] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:42.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:99:0) after 1m0s
[16:42:42.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:99:0)
[16:42:42.395] Timer tick, asking Buildbucket for the build status
[16:42:42.461] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:42.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:100:0) after 1m0s
[16:43:42.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:100:0)
[16:43:42.993] Timer tick, asking Buildbucket for the build status
[16:43:43.053] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:43.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:101:0) after 1m0s
[16:44:43.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:101:0)
[16:44:43.291] Timer tick, asking Buildbucket for the build status
[16:44:43.348] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:43.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:102:0) after 1m0s
[16:45:43.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:102:0)
[16:45:43.539] Timer tick, asking Buildbucket for the build status
[16:45:43.854] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:43.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:103:0) after 1m0s
[16:46:44.119] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:103:0)
[16:46:44.119] Timer tick, asking Buildbucket for the build status
[16:46:44.188] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:44.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:104:0) after 1m0s
[16:47:44.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:104:0)
[16:47:44.292] Timer tick, asking Buildbucket for the build status
[16:47:44.367] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:44.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:105:0) after 1m0s
[16:48:44.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:105:0)
[16:48:44.394] Timer tick, asking Buildbucket for the build status
[16:48:44.462] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:44.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:106:0) after 1m0s
[16:49:44.485] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:106:0)
[16:49:44.485] Timer tick, asking Buildbucket for the build status
[16:49:44.542] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:44.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:107:0) after 1m0s
[16:50:44.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:107:0)
[16:50:44.595] Timer tick, asking Buildbucket for the build status
[16:50:44.730] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:44.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:108:0) after 1m0s
[16:51:44.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:108:0)
[16:51:44.786] Timer tick, asking Buildbucket for the build status
[16:51:44.935] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:44.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:109:0) after 1m0s
[16:52:44.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:109:0)
[16:52:44.985] Timer tick, asking Buildbucket for the build status
[16:52:45.174] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:45.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:110:0) after 1m0s
[16:53:45.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:110:0)
[16:53:45.201] Timer tick, asking Buildbucket for the build status
[16:53:45.324] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:45.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:111:0) after 1m0s
[16:54:45.361] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:111:0)
[16:54:45.361] Timer tick, asking Buildbucket for the build status
[16:54:45.425] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:45.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:112:0) after 1m0s
[16:55:45.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:112:0)
[16:55:45.454] Timer tick, asking Buildbucket for the build status
[16:55:45.557] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:45.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:113:0) after 1m0s
[16:56:45.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:113:0)
[16:56:45.580] Timer tick, asking Buildbucket for the build status
[16:56:45.639] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:45.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:114:0) after 1m0s
[16:57:45.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:114:0)
[16:57:45.711] Timer tick, asking Buildbucket for the build status
[16:57:45.775] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:45.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:115:0) after 1m0s
[16:58:45.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:115:0)
[16:58:45.759] Timer tick, asking Buildbucket for the build status
[16:58:45.829] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:45.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:116:0) after 1m0s
[16:59:45.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:116:0)
[16:59:45.852] Timer tick, asking Buildbucket for the build status
[16:59:45.928] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:45.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:117:0) after 1m0s
[17:00:45.950] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:117:0)
[17:00:45.950] Timer tick, asking Buildbucket for the build status
[17:00:46.056] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:46.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:118:0) after 1m0s
[17:01:46.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:118:0)
[17:01:46.100] Timer tick, asking Buildbucket for the build status
[17:01:46.327] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:46.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:119:0) after 1m0s
[17:02:46.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:119:0)
[17:02:46.382] Timer tick, asking Buildbucket for the build status
[17:02:46.529] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:46.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:120:0) after 1m0s
[17:03:46.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:120:0)
[17:03:46.586] Timer tick, asking Buildbucket for the build status
[17:03:46.700] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:46.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:121:0) after 1m0s
[17:04:46.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:121:0)
[17:04:46.721] Timer tick, asking Buildbucket for the build status
[17:04:46.820] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:46.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:122:0) after 1m0s
[17:05:46.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:122:0)
[17:05:46.825] Timer tick, asking Buildbucket for the build status
[17:05:46.936] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:46.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:123:0) after 1m0s
[17:06:46.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:123:0)
[17:06:46.959] Timer tick, asking Buildbucket for the build status
[17:06:47.081] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:47.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:124:0) after 1m0s
[17:07:47.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:124:0)
[17:07:47.179] Timer tick, asking Buildbucket for the build status
[17:07:47.547] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:47.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:125:0) after 1m0s
[17:08:47.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:125:0)
[17:08:47.627] Timer tick, asking Buildbucket for the build status
[17:08:47.726] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:47.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:126:0) after 1m0s
[17:09:47.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:126:0)
[17:09:47.809] Timer tick, asking Buildbucket for the build status
[17:09:47.865] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:47.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:127:0) after 1m0s
[17:10:47.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:127:0)
[17:10:47.898] Timer tick, asking Buildbucket for the build status
[17:10:48.116] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:48.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:128:0) after 1m0s
[17:11:48.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:128:0)
[17:11:48.144] Timer tick, asking Buildbucket for the build status
[17:11:48.203] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:48.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:129:0) after 1m0s
[17:12:48.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:129:0)
[17:12:48.313] Timer tick, asking Buildbucket for the build status
[17:12:48.377] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:48.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:130:0) after 1m0s
[17:13:48.557] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:130:0)
[17:13:48.557] Timer tick, asking Buildbucket for the build status
[17:13:48.631] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:48.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:131:0) after 1m0s
[17:14:48.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:131:0)
[17:14:48.656] Timer tick, asking Buildbucket for the build status
[17:14:48.711] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:48.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:132:0) after 1m0s
[17:15:48.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:132:0)
[17:15:48.832] Timer tick, asking Buildbucket for the build status
[17:15:49.266] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:49.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:133:0) after 1m0s
[17:16:49.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:133:0)
[17:16:49.315] Timer tick, asking Buildbucket for the build status
[17:16:49.426] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:49.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:134:0) after 1m0s
[17:17:49.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:134:0)
[17:17:49.482] Timer tick, asking Buildbucket for the build status
[17:17:49.724] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:49.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:135:0) after 1m0s
[17:18:49.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:135:0)
[17:18:49.800] Timer tick, asking Buildbucket for the build status
[17:18:49.850] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:49.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:136:0) after 1m0s
[17:19:49.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:136:0)
[17:19:49.880] Timer tick, asking Buildbucket for the build status
[17:19:50.003] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:50.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:137:0) after 1m0s
[17:20:50.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:137:0)
[17:20:50.012] Timer tick, asking Buildbucket for the build status
[17:20:50.136] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:50.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:138:0) after 1m0s
[17:21:50.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:138:0)
[17:21:50.173] Timer tick, asking Buildbucket for the build status
[17:21:50.252] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:50.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:139:0) after 1m0s
[17:22:50.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:139:0)
[17:22:50.245] Timer tick, asking Buildbucket for the build status
[17:22:50.301] Build 8865457059300362688: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:50.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9030887121293775616:140:0) after 1m0s
[17:23:41.411] Received PubSub notification, asking Buildbucket for the build status
[17:23:41.474] Build 8865457059300362688: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:23:41.474] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603646620813529",
"created_by": "project:chromium",
"created_ts": "1603638321550256",
"id": "8865457059300362688",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"66feb3c37aaa1dfef1691d73396f187a641db67f\"}}",
"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\": \"66feb3c37aaa1dfef1691d73396f187a641db67f\", \"project\": \"chromium/src\"}], \"$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-y7dp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 892, \"got_angle_revision\": \"7a0263547cda936b320d80c650b69d7522ee1a9f\", \"got_dawn_revision\": \"42103bc2e911144745d8d8930b07ba7e58553a34\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"66feb3c37aaa1dfef1691d73396f187a641db67f\", \"got_revision_cp\": \"refs/heads/master@{#820617}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"73fe59b55662be0ff07e79da1ece8b9e42415dca\", \"got_v8_revision_cp\": \"refs/heads/8.8.171@{#1}\", \"got_webrtc_revision\": \"e6fa238f76e50a8caa0547074f75443bbfd7b986\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32482}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"66feb3c37aaa1dfef1691d73396f187a641db67f\"}, \"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-y7dp\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5612-26d29a7\"], \"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": "1603638365929691",
"status": "COMPLETED",
"status_changed_ts": "1603646621127570",
"tags": [
"build_address:luci.chromium.ci/win-official/892",
"builder:win-official",
"buildset:commit/git/66feb3c37aaa1dfef1691d73396f187a641db67f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/66feb3c37aaa1dfef1691d73396f187a641db67f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030887121293775616",
"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/8865457059300362688/+/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:4f791c6cdde3da10",
"user_agent:luci-scheduler"
],
"updated_ts": "1603646621135806",
"url": "https://ci.chromium.org/b/8865457059300362688",
"utcnow_ts": "1603646621467461"
}
}
[17:23:41.474] Invocation finished in 2h18m21.208488364s with status SUCCEEDED