[10:44:01.568] New invocation is queued and will start shortly
[10:44:03.258] Starting the invocation (attempt 1)
[10:44:03.312] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:44:03.312] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:44:03.312] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028276249594173712",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"72db127eac699ea942e21531fe9e5ba3806ddc24\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9028276249594173712",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/72db127eac699ea942e21531fe9e5ba3806ddc24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72db127eac699ea942e21531fe9e5ba3806ddc24",
"gitiles_ref:refs/heads/master"
]
}
[10:44:04.648] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606128243447698",
"id": "8862846186957362496",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"72db127eac699ea942e21531fe9e5ba3806ddc24\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606128244344939",
"tags": [
"build_address:luci.chromium.ci/win32-official/980",
"builder:win32-official",
"buildset:commit/git/72db127eac699ea942e21531fe9e5ba3806ddc24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72db127eac699ea942e21531fe9e5ba3806ddc24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028276249594173712",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8862846186957362496/+/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": "1606128244345082",
"url": "https://ci.chromium.org/b/8862846186957362496",
"utcnow_ts": "1606128244614026"
}
}
[10:44:04.648] Task URL: https://ci.chromium.org/b/8862846186957362496
[10:44:04.648] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:2:0) after 1m0s
[10:44:36.048] Received PubSub notification, asking Buildbucket for the build status
[10:44:36.121] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:04.677] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:2:0)
[10:45:04.677] Timer tick, asking Buildbucket for the build status
[10:45:04.766] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:04.766] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:4:0) after 1m0s
[10:46:04.855] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:4:0)
[10:46:04.855] Timer tick, asking Buildbucket for the build status
[10:46:04.978] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:04.978] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:5:0) after 1m0s
[10:47:05.001] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:5:0)
[10:47:05.001] Timer tick, asking Buildbucket for the build status
[10:47:05.092] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:05.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:6:0) after 1m0s
[10:48:05.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:6:0)
[10:48:05.069] Timer tick, asking Buildbucket for the build status
[10:48:05.138] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:05.138] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:7:0) after 1m0s
[10:49:05.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:7:0)
[10:49:05.215] Timer tick, asking Buildbucket for the build status
[10:49:05.346] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:05.346] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:8:0) after 1m0s
[10:50:05.449] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:8:0)
[10:50:05.449] Timer tick, asking Buildbucket for the build status
[10:50:05.780] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:05.780] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:9:0) after 1m0s
[10:51:05.829] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:9:0)
[10:51:05.829] Timer tick, asking Buildbucket for the build status
[10:51:05.898] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:05.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:10:0) after 1m0s
[10:52:05.930] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:10:0)
[10:52:05.930] Timer tick, asking Buildbucket for the build status
[10:52:06.011] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:06.011] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:11:0) after 1m0s
[10:53:06.052] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:11:0)
[10:53:06.052] Timer tick, asking Buildbucket for the build status
[10:53:06.147] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:06.147] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:12:0) after 1m0s
[10:54:06.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:12:0)
[10:54:06.173] Timer tick, asking Buildbucket for the build status
[10:54:06.314] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:06.314] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:13:0) after 1m0s
[10:55:06.418] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:13:0)
[10:55:06.418] Timer tick, asking Buildbucket for the build status
[10:55:06.530] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:06.530] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:14:0) after 1m0s
[10:56:06.607] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:14:0)
[10:56:06.607] Timer tick, asking Buildbucket for the build status
[10:56:06.690] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:06.690] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:15:0) after 1m0s
[10:57:06.952] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:15:0)
[10:57:06.952] Timer tick, asking Buildbucket for the build status
[10:57:07.012] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:07.012] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:16:0) after 1m0s
[10:58:07.244] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:16:0)
[10:58:07.244] Timer tick, asking Buildbucket for the build status
[10:58:07.296] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:07.296] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:17:0) after 1m0s
[10:59:07.305] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:17:0)
[10:59:07.305] Timer tick, asking Buildbucket for the build status
[10:59:07.391] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:07.391] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:18:0) after 1m0s
[11:00:07.475] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:18:0)
[11:00:07.475] Timer tick, asking Buildbucket for the build status
[11:00:07.747] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:07.747] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:19:0) after 1m0s
[11:01:08.541] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:19:0)
[11:01:08.541] Timer tick, asking Buildbucket for the build status
[11:01:08.598] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:08.598] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:20:0) after 1m0s
[11:02:08.859] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:20:0)
[11:02:08.859] Timer tick, asking Buildbucket for the build status
[11:02:08.919] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:08.919] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:21:0) after 1m0s
[11:03:09.040] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:21:0)
[11:03:09.040] Timer tick, asking Buildbucket for the build status
[11:03:09.144] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:09.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:22:0) after 1m0s
[11:04:09.290] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:22:0)
[11:04:09.290] Timer tick, asking Buildbucket for the build status
[11:04:09.477] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:09.477] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:23:0) after 1m0s
[11:05:09.507] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:23:0)
[11:05:09.507] Timer tick, asking Buildbucket for the build status
[11:05:09.565] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:09.565] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:24:0) after 1m0s
[11:06:09.587] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:24:0)
[11:06:09.587] Timer tick, asking Buildbucket for the build status
[11:06:09.644] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:09.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:25:0) after 1m0s
[11:07:09.701] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:25:0)
[11:07:09.701] Timer tick, asking Buildbucket for the build status
[11:07:09.771] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:09.771] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:26:0) after 1m0s
[11:08:09.835] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:26:0)
[11:08:09.836] Timer tick, asking Buildbucket for the build status
[11:08:09.899] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:09.899] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:27:0) after 1m0s
[11:09:10.079] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:27:0)
[11:09:10.079] Timer tick, asking Buildbucket for the build status
[11:09:10.181] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:10.181] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:28:0) after 1m0s
[11:10:10.248] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:28:0)
[11:10:10.248] Timer tick, asking Buildbucket for the build status
[11:10:10.554] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:10.554] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:29:0) after 1m0s
[11:11:10.653] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:29:0)
[11:11:10.653] Timer tick, asking Buildbucket for the build status
[11:11:10.770] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:10.770] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:30:0) after 1m0s
[11:12:10.816] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:30:0)
[11:12:10.816] Timer tick, asking Buildbucket for the build status
[11:12:10.921] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:10.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:31:0) after 1m0s
[11:13:10.973] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:31:0)
[11:13:10.973] Timer tick, asking Buildbucket for the build status
[11:13:11.049] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:11.049] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:32:0) after 1m0s
[11:14:11.120] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:32:0)
[11:14:11.120] Timer tick, asking Buildbucket for the build status
[11:14:11.246] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:11.246] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:33:0) after 1m0s
[11:15:11.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:33:0)
[11:15:11.505] Timer tick, asking Buildbucket for the build status
[11:15:12.010] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:12.010] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:34:0) after 1m0s
[11:16:12.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:34:0)
[11:16:12.131] Timer tick, asking Buildbucket for the build status
[11:16:12.708] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:12.708] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:35:0) after 1m0s
[11:17:12.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:35:0)
[11:17:12.798] Timer tick, asking Buildbucket for the build status
[11:17:12.939] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:12.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:36:0) after 1m0s
[11:18:12.951] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:36:0)
[11:18:12.951] Timer tick, asking Buildbucket for the build status
[11:18:13.057] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:13.057] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:37:0) after 1m0s
[11:19:13.177] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:37:0)
[11:19:13.177] Timer tick, asking Buildbucket for the build status
[11:19:13.259] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:13.259] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:38:0) after 1m0s
[11:20:13.341] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:38:0)
[11:20:13.341] Timer tick, asking Buildbucket for the build status
[11:20:13.388] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:13.388] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:39:0) after 1m0s
[11:21:13.428] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:39:0)
[11:21:13.428] Timer tick, asking Buildbucket for the build status
[11:21:13.502] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:13.502] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:40:0) after 1m0s
[11:22:13.506] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:40:0)
[11:22:13.506] Timer tick, asking Buildbucket for the build status
[11:22:13.562] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:13.562] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:41:0) after 1m0s
[11:23:13.575] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:41:0)
[11:23:13.575] Timer tick, asking Buildbucket for the build status
[11:23:13.624] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:13.624] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:42:0) after 1m0s
[11:24:13.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:42:0)
[11:24:13.663] Timer tick, asking Buildbucket for the build status
[11:24:14.064] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:14.064] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:43:0) after 1m0s
[11:25:14.092] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:43:0)
[11:25:14.092] Timer tick, asking Buildbucket for the build status
[11:25:14.188] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:14.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:44:0) after 1m0s
[11:26:14.269] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:44:0)
[11:26:14.269] Timer tick, asking Buildbucket for the build status
[11:26:14.332] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:14.332] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:45:0) after 1m0s
[11:27:14.336] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:45:0)
[11:27:14.336] Timer tick, asking Buildbucket for the build status
[11:27:14.402] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:14.402] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:46:0) after 1m0s
[11:28:14.424] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:46:0)
[11:28:14.424] Timer tick, asking Buildbucket for the build status
[11:28:14.603] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:14.603] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:47:0) after 1m0s
[11:29:14.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:47:0)
[11:29:14.624] Timer tick, asking Buildbucket for the build status
[11:29:14.720] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:14.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:48:0) after 1m0s
[11:30:14.777] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:48:0)
[11:30:14.777] Timer tick, asking Buildbucket for the build status
[11:30:14.884] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:14.884] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:49:0) after 1m0s
[11:31:14.976] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:49:0)
[11:31:14.976] Timer tick, asking Buildbucket for the build status
[11:31:15.037] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:15.037] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:50:0) after 1m0s
[11:32:15.137] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:50:0)
[11:32:15.137] Timer tick, asking Buildbucket for the build status
[11:32:15.321] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:15.321] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:51:0) after 1m0s
[11:33:15.392] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:51:0)
[11:33:15.392] Timer tick, asking Buildbucket for the build status
[11:33:15.499] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:15.499] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:52:0) after 1m0s
[11:34:15.619] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:52:0)
[11:34:15.619] Timer tick, asking Buildbucket for the build status
[11:34:15.908] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:15.908] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:53:0) after 1m0s
[11:35:15.976] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:53:0)
[11:35:15.976] Timer tick, asking Buildbucket for the build status
[11:35:16.044] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:16.044] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:54:0) after 1m0s
[11:36:16.105] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:54:0)
[11:36:16.105] Timer tick, asking Buildbucket for the build status
[11:36:16.201] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:16.201] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:55:0) after 1m0s
[11:37:16.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:55:0)
[11:37:16.278] Timer tick, asking Buildbucket for the build status
[11:37:16.344] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:16.344] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:56:0) after 1m0s
[11:38:16.383] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:56:0)
[11:38:16.383] Timer tick, asking Buildbucket for the build status
[11:38:16.457] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:16.457] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:57:0) after 1m0s
[11:39:16.456] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:57:0)
[11:39:16.456] Timer tick, asking Buildbucket for the build status
[11:39:16.764] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:16.764] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:58:0) after 1m0s
[11:40:16.768] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:58:0)
[11:40:16.768] Timer tick, asking Buildbucket for the build status
[11:40:16.852] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:16.852] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:59:0) after 1m0s
[11:41:16.869] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:59:0)
[11:41:16.869] Timer tick, asking Buildbucket for the build status
[11:41:16.923] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:16.923] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:60:0) after 1m0s
[11:42:16.973] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:60:0)
[11:42:16.973] Timer tick, asking Buildbucket for the build status
[11:42:17.035] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:17.035] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:61:0) after 1m0s
[11:43:17.140] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:61:0)
[11:43:17.140] Timer tick, asking Buildbucket for the build status
[11:43:17.233] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:17.233] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:62:0) after 1m0s
[11:44:17.257] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:62:0)
[11:44:17.257] Timer tick, asking Buildbucket for the build status
[11:44:17.373] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:17.373] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:63:0) after 1m0s
[11:45:17.403] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:63:0)
[11:45:17.403] Timer tick, asking Buildbucket for the build status
[11:45:17.720] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:17.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:64:0) after 1m0s
[11:46:17.742] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:64:0)
[11:46:17.742] Timer tick, asking Buildbucket for the build status
[11:46:18.075] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:18.075] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:65:0) after 1m0s
[11:47:18.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:65:0)
[11:47:18.057] Timer tick, asking Buildbucket for the build status
[11:47:18.159] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:18.159] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:66:0) after 1m0s
[11:48:18.189] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:66:0)
[11:48:18.189] Timer tick, asking Buildbucket for the build status
[11:48:18.264] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:18.264] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:67:0) after 1m0s
[11:49:18.363] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:67:0)
[11:49:18.363] Timer tick, asking Buildbucket for the build status
[11:49:18.504] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:18.504] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:68:0) after 1m0s
[11:50:18.588] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:68:0)
[11:50:18.589] Timer tick, asking Buildbucket for the build status
[11:50:18.658] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:18.658] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:69:0) after 1m0s
[11:51:18.646] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:69:0)
[11:51:18.646] Timer tick, asking Buildbucket for the build status
[11:51:18.776] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:18.776] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:70:0) after 1m0s
[11:52:18.858] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:70:0)
[11:52:18.858] Timer tick, asking Buildbucket for the build status
[11:52:18.929] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:18.929] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:71:0) after 1m0s
[11:53:19.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:71:0)
[11:53:19.038] Timer tick, asking Buildbucket for the build status
[11:53:19.110] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:19.110] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:72:0) after 1m0s
[11:54:19.258] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:72:0)
[11:54:19.258] Timer tick, asking Buildbucket for the build status
[11:54:19.595] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:19.595] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:73:0) after 1m0s
[11:55:19.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:73:0)
[11:55:19.700] Timer tick, asking Buildbucket for the build status
[11:55:19.770] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:19.770] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:74:0) after 1m0s
[11:56:19.831] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:74:0)
[11:56:19.831] Timer tick, asking Buildbucket for the build status
[11:56:19.904] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:19.904] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:75:0) after 1m0s
[11:57:19.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:75:0)
[11:57:19.928] Timer tick, asking Buildbucket for the build status
[11:57:19.985] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:19.985] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:76:0) after 1m0s
[11:58:20.040] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:76:0)
[11:58:20.040] Timer tick, asking Buildbucket for the build status
[11:58:20.209] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:20.209] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:77:0) after 1m0s
[11:59:20.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:77:0)
[11:59:20.274] Timer tick, asking Buildbucket for the build status
[11:59:20.389] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:20.389] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:78:0) after 1m0s
[12:00:20.434] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:78:0)
[12:00:20.434] Timer tick, asking Buildbucket for the build status
[12:00:20.527] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:79:0) after 1m0s
[12:01:20.571] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:79:0)
[12:01:20.571] Timer tick, asking Buildbucket for the build status
[12:01:20.676] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:20.676] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:80:0) after 1m0s
[12:02:20.727] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:80:0)
[12:02:20.727] Timer tick, asking Buildbucket for the build status
[12:02:20.924] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:20.924] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:81:0) after 1m0s
[12:03:21.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:81:0)
[12:03:21.077] Timer tick, asking Buildbucket for the build status
[12:03:21.150] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:21.150] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:82:0) after 1m0s
[12:04:21.308] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:82:0)
[12:04:21.308] Timer tick, asking Buildbucket for the build status
[12:04:21.402] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:21.402] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:83:0) after 1m0s
[12:05:21.456] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:83:0)
[12:05:21.456] Timer tick, asking Buildbucket for the build status
[12:05:21.527] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:21.527] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:84:0) after 1m0s
[12:06:21.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:84:0)
[12:06:21.573] Timer tick, asking Buildbucket for the build status
[12:06:21.638] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:21.638] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:85:0) after 1m0s
[12:07:21.944] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:85:0)
[12:07:21.944] Timer tick, asking Buildbucket for the build status
[12:07:22.011] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:22.011] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:86:0) after 1m0s
[12:08:22.185] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:86:0)
[12:08:22.185] Timer tick, asking Buildbucket for the build status
[12:08:22.262] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:22.262] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:87:0) after 1m0s
[12:09:22.389] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:87:0)
[12:09:22.389] Timer tick, asking Buildbucket for the build status
[12:09:22.675] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:22.675] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:88:0) after 1m0s
[12:10:22.704] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:88:0)
[12:10:22.704] Timer tick, asking Buildbucket for the build status
[12:10:22.789] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:22.789] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:89:0) after 1m0s
[12:11:22.790] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:89:0)
[12:11:22.790] Timer tick, asking Buildbucket for the build status
[12:11:22.853] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:22.853] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:90:0) after 1m0s
[12:12:23.031] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:90:0)
[12:12:23.031] Timer tick, asking Buildbucket for the build status
[12:12:23.126] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:23.126] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:91:0) after 1m0s
[12:13:23.198] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:91:0)
[12:13:23.198] Timer tick, asking Buildbucket for the build status
[12:13:23.266] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:23.266] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:92:0) after 1m0s
[12:14:23.373] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:92:0)
[12:14:23.373] Timer tick, asking Buildbucket for the build status
[12:14:23.491] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:23.491] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:93:0) after 1m0s
[12:15:24.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:93:0)
[12:15:24.557] Timer tick, asking Buildbucket for the build status
[12:15:24.635] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:24.635] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:94:0) after 1m0s
[12:16:24.782] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:94:0)
[12:16:24.782] Timer tick, asking Buildbucket for the build status
[12:16:24.849] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:24.849] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:95:0) after 1m0s
[12:17:24.932] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:95:0)
[12:17:24.932] Timer tick, asking Buildbucket for the build status
[12:17:24.988] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:24.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:96:0) after 1m0s
[12:18:25.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:96:0)
[12:18:25.076] Timer tick, asking Buildbucket for the build status
[12:18:25.134] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:25.134] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:97:0) after 1m0s
[12:19:25.602] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:97:0)
[12:19:25.602] Timer tick, asking Buildbucket for the build status
[12:19:25.717] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:25.717] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:98:0) after 1m0s
[12:20:26.141] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:98:0)
[12:20:26.141] Timer tick, asking Buildbucket for the build status
[12:20:26.220] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:26.220] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:99:0) after 1m0s
[12:21:26.248] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:99:0)
[12:21:26.248] Timer tick, asking Buildbucket for the build status
[12:21:26.309] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:26.309] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:100:0) after 1m0s
[12:22:26.465] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:100:0)
[12:22:26.465] Timer tick, asking Buildbucket for the build status
[12:22:26.541] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:26.541] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:101:0) after 1m0s
[12:23:26.886] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:101:0)
[12:23:26.886] Timer tick, asking Buildbucket for the build status
[12:23:26.970] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:26.970] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:102:0) after 1m0s
[12:24:27.167] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:102:0)
[12:24:27.167] Timer tick, asking Buildbucket for the build status
[12:24:27.256] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:27.256] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:103:0) after 1m0s
[12:25:27.314] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:103:0)
[12:25:27.314] Timer tick, asking Buildbucket for the build status
[12:25:27.802] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:27.802] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:104:0) after 1m0s
[12:26:27.864] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:104:0)
[12:26:27.864] Timer tick, asking Buildbucket for the build status
[12:26:27.927] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:27.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:105:0) after 1m0s
[12:27:28.045] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:105:0)
[12:27:28.045] Timer tick, asking Buildbucket for the build status
[12:27:28.166] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:28.166] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:106:0) after 1m0s
[12:28:28.261] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:106:0)
[12:28:28.261] Timer tick, asking Buildbucket for the build status
[12:28:28.322] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:28.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:107:0) after 1m0s
[12:29:28.449] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:107:0)
[12:29:28.449] Timer tick, asking Buildbucket for the build status
[12:29:28.620] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:28.620] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:108:0) after 1m0s
[12:30:28.711] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:108:0)
[12:30:28.711] Timer tick, asking Buildbucket for the build status
[12:30:28.778] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:28.778] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:109:0) after 1m0s
[12:31:28.897] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:109:0)
[12:31:28.897] Timer tick, asking Buildbucket for the build status
[12:31:28.962] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:28.962] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:110:0) after 1m0s
[12:32:29.023] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:110:0)
[12:32:29.023] Timer tick, asking Buildbucket for the build status
[12:32:29.073] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:29.073] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:111:0) after 1m0s
[12:33:29.124] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:111:0)
[12:33:29.124] Timer tick, asking Buildbucket for the build status
[12:33:29.444] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:29.444] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:112:0) after 1m0s
[12:34:29.515] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:112:0)
[12:34:29.515] Timer tick, asking Buildbucket for the build status
[12:34:29.812] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:29.812] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:113:0) after 1m0s
[12:35:29.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:113:0)
[12:35:29.918] Timer tick, asking Buildbucket for the build status
[12:35:30.385] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:30.385] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:114:0) after 1m0s
[12:36:30.496] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:114:0)
[12:36:30.496] Timer tick, asking Buildbucket for the build status
[12:36:30.564] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:30.564] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:115:0) after 1m0s
[12:37:30.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:115:0)
[12:37:30.613] Timer tick, asking Buildbucket for the build status
[12:37:31.247] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:31.247] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:116:0) after 1m0s
[12:38:31.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:116:0)
[12:38:31.508] Timer tick, asking Buildbucket for the build status
[12:38:31.665] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:31.665] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:117:0) after 1m0s
[12:39:31.747] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:117:0)
[12:39:31.747] Timer tick, asking Buildbucket for the build status
[12:39:31.848] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:31.848] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:118:0) after 1m0s
[12:40:31.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:118:0)
[12:40:31.918] Timer tick, asking Buildbucket for the build status
[12:40:31.980] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:31.980] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:119:0) after 1m0s
[12:41:32.090] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:119:0)
[12:41:32.090] Timer tick, asking Buildbucket for the build status
[12:41:32.252] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:32.252] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:120:0) after 1m0s
[12:42:32.303] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:120:0)
[12:42:32.303] Timer tick, asking Buildbucket for the build status
[12:42:32.388] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:32.388] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:121:0) after 1m0s
[12:43:32.424] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:121:0)
[12:43:32.424] Timer tick, asking Buildbucket for the build status
[12:43:32.491] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:32.491] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:122:0) after 1m0s
[12:44:32.630] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:122:0)
[12:44:32.630] Timer tick, asking Buildbucket for the build status
[12:44:32.724] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:32.724] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:123:0) after 1m0s
[12:45:32.860] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:123:0)
[12:45:32.860] Timer tick, asking Buildbucket for the build status
[12:45:32.924] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:32.924] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:124:0) after 1m0s
[12:46:33.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:124:0)
[12:46:33.008] Timer tick, asking Buildbucket for the build status
[12:46:33.087] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:33.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:125:0) after 1m0s
[12:47:33.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:125:0)
[12:47:33.184] Timer tick, asking Buildbucket for the build status
[12:47:33.329] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:33.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:126:0) after 1m0s
[12:48:33.584] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:126:0)
[12:48:33.584] Timer tick, asking Buildbucket for the build status
[12:48:33.810] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:33.810] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:127:0) after 1m0s
[12:49:33.856] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:127:0)
[12:49:33.856] Timer tick, asking Buildbucket for the build status
[12:49:33.969] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:33.969] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:128:0) after 1m0s
[12:50:34.161] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:128:0)
[12:50:34.161] Timer tick, asking Buildbucket for the build status
[12:50:34.292] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:34.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:129:0) after 1m0s
[12:51:34.360] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:129:0)
[12:51:34.360] Timer tick, asking Buildbucket for the build status
[12:51:34.421] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:34.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:130:0) after 1m0s
[12:52:34.552] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:130:0)
[12:52:34.552] Timer tick, asking Buildbucket for the build status
[12:52:34.619] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:34.619] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:131:0) after 1m0s
[12:53:34.630] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:131:0)
[12:53:34.630] Timer tick, asking Buildbucket for the build status
[12:53:34.747] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:34.747] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:132:0) after 1m0s
[12:54:34.774] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:132:0)
[12:54:34.774] Timer tick, asking Buildbucket for the build status
[12:54:34.881] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:34.881] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:133:0) after 1m0s
[12:55:34.975] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:133:0)
[12:55:34.975] Timer tick, asking Buildbucket for the build status
[12:55:35.112] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:35.112] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:134:0) after 1m0s
[12:56:35.137] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:134:0)
[12:56:35.137] Timer tick, asking Buildbucket for the build status
[12:56:35.441] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:35.441] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:135:0) after 1m0s
[12:57:35.536] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:135:0)
[12:57:35.536] Timer tick, asking Buildbucket for the build status
[12:57:35.652] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:35.653] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:136:0) after 1m0s
[12:58:35.717] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:136:0)
[12:58:35.717] Timer tick, asking Buildbucket for the build status
[12:58:36.014] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:36.014] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:137:0) after 1m0s
[12:59:36.045] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:137:0)
[12:59:36.045] Timer tick, asking Buildbucket for the build status
[12:59:36.148] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:36.148] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:138:0) after 1m0s
[13:00:36.271] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:138:0)
[13:00:36.271] Timer tick, asking Buildbucket for the build status
[13:00:36.332] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:36.332] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:139:0) after 1m0s
[13:01:36.407] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:139:0)
[13:01:36.407] Timer tick, asking Buildbucket for the build status
[13:01:36.509] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:36.509] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:140:0) after 1m0s
[13:02:36.629] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:140:0)
[13:02:36.629] Timer tick, asking Buildbucket for the build status
[13:02:36.719] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:36.719] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:141:0) after 1m0s
[13:03:36.832] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:141:0)
[13:03:36.832] Timer tick, asking Buildbucket for the build status
[13:03:36.900] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:36.900] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:142:0) after 1m0s
[13:04:36.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:142:0)
[13:04:36.979] Timer tick, asking Buildbucket for the build status
[13:04:37.103] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:37.103] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:143:0) after 1m0s
[13:05:37.144] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:143:0)
[13:05:37.144] Timer tick, asking Buildbucket for the build status
[13:05:37.210] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:37.210] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:144:0) after 1m0s
[13:06:37.281] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:144:0)
[13:06:37.281] Timer tick, asking Buildbucket for the build status
[13:06:37.364] Build 8862846186957362496: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:37.364] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028276249594173712:145:0) after 1m0s
[13:06:58.513] Received PubSub notification, asking Buildbucket for the build status
[13:06:58.621] Build 8862846186957362496: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:06:58.621] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606136817437069",
"created_by": "project:chromium",
"created_ts": "1606128243447698",
"id": "8862846186957362496",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"72db127eac699ea942e21531fe9e5ba3806ddc24\"}}",
"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\": \"72db127eac699ea942e21531fe9e5ba3806ddc24\", \"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-9-y4fe\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 980, \"got_angle_revision\": \"a304f111386d06c9cba569506ed4b47227d0b6c8\", \"got_dawn_revision\": \"546a7c98c89718f2dadf1ae07088fa7cf2ccb53f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"72db127eac699ea942e21531fe9e5ba3806ddc24\", \"got_revision_cp\": \"refs/heads/master@{#830125}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"6d612051c083238db89541be4fbb2d624a9baef4\", \"got_v8_revision\": \"805e8b5987e5fd07adbe998f0c21f84cb20468b8\", \"got_v8_revision_cp\": \"refs/heads/8.9.32@{#1}\", \"got_webrtc_revision\": \"79348b55df90f3f7d00f12fbc0c679cff147ba81\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32664}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"72db127eac699ea942e21531fe9e5ba3806ddc24\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-9-y4fe\"], \"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\": [\"5637-4d8b832\"], \"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": "1606128275469235",
"status": "COMPLETED",
"status_changed_ts": "1606136818031914",
"tags": [
"build_address:luci.chromium.ci/win32-official/980",
"builder:win32-official",
"buildset:commit/git/72db127eac699ea942e21531fe9e5ba3806ddc24",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/72db127eac699ea942e21531fe9e5ba3806ddc24",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028276249594173712",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8862846186957362496/+/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:500d859f9e679710",
"user_agent:luci-scheduler"
],
"updated_ts": "1606136818101135",
"url": "https://ci.chromium.org/b/8862846186957362496",
"utcnow_ts": "1606136818612030"
}
}
[13:06:58.621] Invocation finished in 2h22m57.072419222s with status SUCCEEDED