[10:48:57.009] New invocation is queued and will start shortly
[10:48:58.287] Starting the invocation (attempt 1)
[10:48:58.321] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:48:58.322] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:48:58.322] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032171609353594800",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"99158b00290cf76b25389a20761d0ccf562f410f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9032171609353594800",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/99158b00290cf76b25389a20761d0ccf562f410f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99158b00290cf76b25389a20761d0ccf562f410f",
"gitiles_ref:refs/heads/master"
]
}
[10:48:58.933] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1602413338406778",
"id": "8866741547226077120",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"99158b00290cf76b25389a20761d0ccf562f410f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602413338810213",
"tags": [
"build_address:luci.chromium.ci/win-official/789",
"builder:win-official",
"buildset:commit/git/99158b00290cf76b25389a20761d0ccf562f410f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99158b00290cf76b25389a20761d0ccf562f410f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032171609353594800",
"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/8866741547226077120/+/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": "1602413338810327",
"url": "https://ci.chromium.org/b/8866741547226077120",
"utcnow_ts": "1602413338925623"
}
}
[10:48:58.933] Task URL: https://ci.chromium.org/b/8866741547226077120
[10:48:58.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:2:0) after 1m0s
[10:49:58.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:2:0)
[10:49:58.951] Timer tick, asking Buildbucket for the build status
[10:49:59.044] Build 8866741547226077120: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:49:59.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:3:0) after 1m0s
[10:50:02.449] Received PubSub notification, asking Buildbucket for the build status
[10:50:02.609] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:59.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:3:0)
[10:50:59.066] Timer tick, asking Buildbucket for the build status
[10:50:59.142] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:59.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:5:0) after 1m0s
[10:51:59.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:5:0)
[10:51:59.161] Timer tick, asking Buildbucket for the build status
[10:51:59.245] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:59.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:6:0) after 1m0s
[10:52:59.265] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:6:0)
[10:52:59.265] Timer tick, asking Buildbucket for the build status
[10:52:59.339] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:59.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:7:0) after 1m0s
[10:53:59.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:7:0)
[10:53:59.367] Timer tick, asking Buildbucket for the build status
[10:53:59.444] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:59.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:8:0) after 1m0s
[10:54:59.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:8:0)
[10:54:59.500] Timer tick, asking Buildbucket for the build status
[10:54:59.615] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:59.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:9:0) after 1m0s
[10:55:59.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:9:0)
[10:55:59.636] Timer tick, asking Buildbucket for the build status
[10:55:59.707] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:59.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:10:0) after 1m0s
[10:56:59.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:10:0)
[10:56:59.729] Timer tick, asking Buildbucket for the build status
[10:56:59.824] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:59.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:11:0) after 1m0s
[10:57:59.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:11:0)
[10:57:59.847] Timer tick, asking Buildbucket for the build status
[10:57:59.924] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:59.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:12:0) after 1m0s
[10:58:59.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:12:0)
[10:58:59.945] Timer tick, asking Buildbucket for the build status
[10:59:00.110] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:00.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:13:0) after 1m0s
[11:00:00.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:13:0)
[11:00:00.132] Timer tick, asking Buildbucket for the build status
[11:00:00.228] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:00.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:14:0) after 1m0s
[11:01:00.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:14:0)
[11:01:00.249] Timer tick, asking Buildbucket for the build status
[11:01:00.409] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:00.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:15:0) after 1m0s
[11:02:00.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:15:0)
[11:02:00.423] Timer tick, asking Buildbucket for the build status
[11:02:00.483] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:00.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:16:0) after 1m0s
[11:03:00.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:16:0)
[11:03:00.819] Timer tick, asking Buildbucket for the build status
[11:03:00.881] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:00.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:17:0) after 1m0s
[11:04:00.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:17:0)
[11:04:00.903] Timer tick, asking Buildbucket for the build status
[11:04:01.043] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:01.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:18:0) after 1m0s
[11:05:01.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:18:0)
[11:05:01.575] Timer tick, asking Buildbucket for the build status
[11:05:01.639] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:01.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:19:0) after 1m0s
[11:06:01.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:19:0)
[11:06:01.703] Timer tick, asking Buildbucket for the build status
[11:06:01.767] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:01.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:20:0) after 1m0s
[11:07:01.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:20:0)
[11:07:01.784] Timer tick, asking Buildbucket for the build status
[11:07:01.857] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:01.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:21:0) after 1m0s
[11:08:01.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:21:0)
[11:08:01.878] Timer tick, asking Buildbucket for the build status
[11:08:02.282] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:02.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:22:0) after 1m0s
[11:09:02.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:22:0)
[11:09:02.403] Timer tick, asking Buildbucket for the build status
[11:09:02.510] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:02.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:23:0) after 1m0s
[11:10:02.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:23:0)
[11:10:02.547] Timer tick, asking Buildbucket for the build status
[11:10:02.884] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:02.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:24:0) after 1m0s
[11:11:02.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:24:0)
[11:11:02.902] Timer tick, asking Buildbucket for the build status
[11:11:03.098] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:03.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:25:0) after 1m0s
[11:12:03.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:25:0)
[11:12:03.121] Timer tick, asking Buildbucket for the build status
[11:12:03.305] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:03.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:26:0) after 1m0s
[11:13:03.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:26:0)
[11:13:03.348] Timer tick, asking Buildbucket for the build status
[11:13:03.725] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:03.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:27:0) after 1m0s
[11:14:03.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:27:0)
[11:14:03.756] Timer tick, asking Buildbucket for the build status
[11:14:03.860] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:03.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:28:0) after 1m0s
[11:15:03.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:28:0)
[11:15:03.884] Timer tick, asking Buildbucket for the build status
[11:15:03.967] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:03.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:29:0) after 1m0s
[11:16:04.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:29:0)
[11:16:04.016] Timer tick, asking Buildbucket for the build status
[11:16:04.116] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:04.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:30:0) after 1m0s
[11:17:04.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:30:0)
[11:17:04.147] Timer tick, asking Buildbucket for the build status
[11:17:04.252] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:04.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:31:0) after 1m0s
[11:18:04.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:31:0)
[11:18:04.275] Timer tick, asking Buildbucket for the build status
[11:18:04.337] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:04.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:32:0) after 1m0s
[11:19:04.435] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:32:0)
[11:19:04.435] Timer tick, asking Buildbucket for the build status
[11:19:04.592] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:04.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:33:0) after 1m0s
[11:20:04.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:33:0)
[11:20:04.647] Timer tick, asking Buildbucket for the build status
[11:20:04.724] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:04.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:34:0) after 1m0s
[11:21:04.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:34:0)
[11:21:04.864] Timer tick, asking Buildbucket for the build status
[11:21:05.055] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:05.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:35:0) after 1m0s
[11:22:05.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:35:0)
[11:22:05.195] Timer tick, asking Buildbucket for the build status
[11:22:06.579] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:06.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:36:0) after 1m0s
[11:23:06.610] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:36:0)
[11:23:06.610] Timer tick, asking Buildbucket for the build status
[11:23:06.865] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:06.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:37:0) after 1m0s
[11:24:06.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:37:0)
[11:24:06.930] Timer tick, asking Buildbucket for the build status
[11:24:07.112] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:07.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:38:0) after 1m0s
[11:25:07.175] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:38:0)
[11:25:07.175] Timer tick, asking Buildbucket for the build status
[11:25:07.235] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:07.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:39:0) after 1m0s
[11:26:07.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:39:0)
[11:26:07.310] Timer tick, asking Buildbucket for the build status
[11:26:07.496] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:07.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:40:0) after 1m0s
[11:27:07.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:40:0)
[11:27:07.526] Timer tick, asking Buildbucket for the build status
[11:27:07.572] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:07.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:41:0) after 1m0s
[11:28:07.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:41:0)
[11:28:07.643] Timer tick, asking Buildbucket for the build status
[11:28:07.710] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:07.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:42:0) after 1m0s
[11:29:07.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:42:0)
[11:29:07.731] Timer tick, asking Buildbucket for the build status
[11:29:07.833] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:07.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:43:0) after 1m0s
[11:30:07.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:43:0)
[11:30:07.914] Timer tick, asking Buildbucket for the build status
[11:30:07.978] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:07.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:44:0) after 1m0s
[11:31:09.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:44:0)
[11:31:09.088] Timer tick, asking Buildbucket for the build status
[11:31:09.174] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:09.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:45:0) after 1m0s
[11:32:09.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:45:0)
[11:32:09.422] Timer tick, asking Buildbucket for the build status
[11:32:10.807] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:10.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:46:0) after 1m0s
[11:33:10.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:46:0)
[11:33:10.827] Timer tick, asking Buildbucket for the build status
[11:33:10.916] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:10.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:47:0) after 1m0s
[11:34:10.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:47:0)
[11:34:10.937] Timer tick, asking Buildbucket for the build status
[11:34:11.059] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:11.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:48:0) after 1m0s
[11:35:11.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:48:0)
[11:35:11.118] Timer tick, asking Buildbucket for the build status
[11:35:11.172] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:11.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:49:0) after 1m0s
[11:36:11.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:49:0)
[11:36:11.244] Timer tick, asking Buildbucket for the build status
[11:36:11.309] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:11.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:50:0) after 1m0s
[11:37:11.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:50:0)
[11:37:11.448] Timer tick, asking Buildbucket for the build status
[11:37:11.521] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:11.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:51:0) after 1m0s
[11:38:11.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:51:0)
[11:38:11.596] Timer tick, asking Buildbucket for the build status
[11:38:11.660] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:11.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:52:0) after 1m0s
[11:39:11.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:52:0)
[11:39:11.763] Timer tick, asking Buildbucket for the build status
[11:39:11.856] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:11.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:53:0) after 1m0s
[11:40:11.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:53:0)
[11:40:11.886] Timer tick, asking Buildbucket for the build status
[11:40:11.939] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:11.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:54:0) after 1m0s
[11:41:11.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:54:0)
[11:41:11.935] Timer tick, asking Buildbucket for the build status
[11:41:11.999] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:11.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:55:0) after 1m0s
[11:42:12.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:55:0)
[11:42:12.109] Timer tick, asking Buildbucket for the build status
[11:42:12.178] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:12.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:56:0) after 1m0s
[11:43:12.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:56:0)
[11:43:12.342] Timer tick, asking Buildbucket for the build status
[11:43:12.497] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:12.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:57:0) after 1m0s
[11:44:12.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:57:0)
[11:44:12.568] Timer tick, asking Buildbucket for the build status
[11:44:12.665] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:12.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:58:0) after 1m0s
[11:45:12.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:58:0)
[11:45:12.691] Timer tick, asking Buildbucket for the build status
[11:45:12.755] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:12.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:59:0) after 1m0s
[11:46:12.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:59:0)
[11:46:12.727] Timer tick, asking Buildbucket for the build status
[11:46:12.794] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:12.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:60:0) after 1m0s
[11:47:12.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:60:0)
[11:47:12.907] Timer tick, asking Buildbucket for the build status
[11:47:13.002] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:13.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:61:0) after 1m0s
[11:48:13.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:61:0)
[11:48:13.025] Timer tick, asking Buildbucket for the build status
[11:48:13.092] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:13.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:62:0) after 1m0s
[11:49:13.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:62:0)
[11:49:13.249] Timer tick, asking Buildbucket for the build status
[11:49:13.346] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:13.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:63:0) after 1m0s
[11:50:13.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:63:0)
[11:50:13.393] Timer tick, asking Buildbucket for the build status
[11:50:13.672] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:13.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:64:0) after 1m0s
[11:51:13.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:64:0)
[11:51:13.696] Timer tick, asking Buildbucket for the build status
[11:51:14.104] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:14.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:65:0) after 1m0s
[11:52:14.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:65:0)
[11:52:14.292] Timer tick, asking Buildbucket for the build status
[11:52:14.350] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:14.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:66:0) after 1m0s
[11:53:14.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:66:0)
[11:53:14.347] Timer tick, asking Buildbucket for the build status
[11:53:14.605] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:14.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:67:0) after 1m0s
[11:54:14.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:67:0)
[11:54:14.631] Timer tick, asking Buildbucket for the build status
[11:54:15.097] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:15.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:68:0) after 1m0s
[11:55:15.156] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:68:0)
[11:55:15.156] Timer tick, asking Buildbucket for the build status
[11:55:15.371] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:15.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:69:0) after 1m0s
[11:56:15.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:69:0)
[11:56:15.484] Timer tick, asking Buildbucket for the build status
[11:56:15.543] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:15.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:70:0) after 1m0s
[11:57:15.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:70:0)
[11:57:15.597] Timer tick, asking Buildbucket for the build status
[11:57:15.681] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:15.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:71:0) after 1m0s
[11:58:15.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:71:0)
[11:58:15.807] Timer tick, asking Buildbucket for the build status
[11:58:15.864] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:15.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:72:0) after 1m0s
[11:59:15.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:72:0)
[11:59:15.958] Timer tick, asking Buildbucket for the build status
[11:59:16.305] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:16.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:73:0) after 1m0s
[12:00:16.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:73:0)
[12:00:16.370] Timer tick, asking Buildbucket for the build status
[12:00:16.870] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:16.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:74:0) after 1m0s
[12:01:24.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:74:0)
[12:01:24.288] Timer tick, asking Buildbucket for the build status
[12:01:24.365] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:24.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:75:0) after 1m0s
[12:02:24.389] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:75:0)
[12:02:24.389] Timer tick, asking Buildbucket for the build status
[12:02:24.524] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:24.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:76:0) after 1m0s
[12:03:24.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:76:0)
[12:03:24.545] Timer tick, asking Buildbucket for the build status
[12:03:24.601] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:24.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:77:0) after 1m0s
[12:04:24.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:77:0)
[12:04:24.720] Timer tick, asking Buildbucket for the build status
[12:04:24.832] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:24.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:78:0) after 1m0s
[12:05:24.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:78:0)
[12:05:24.882] Timer tick, asking Buildbucket for the build status
[12:05:25.036] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:25.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:79:0) after 1m0s
[12:06:25.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:79:0)
[12:06:25.159] Timer tick, asking Buildbucket for the build status
[12:06:25.242] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:25.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:80:0) after 1m0s
[12:07:25.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:80:0)
[12:07:25.268] Timer tick, asking Buildbucket for the build status
[12:07:25.602] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:25.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:81:0) after 1m0s
[12:08:25.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:81:0)
[12:08:25.607] Timer tick, asking Buildbucket for the build status
[12:08:25.901] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:25.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:82:0) after 1m0s
[12:09:26.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:82:0)
[12:09:26.095] Timer tick, asking Buildbucket for the build status
[12:09:26.213] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:26.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:83:0) after 1m0s
[12:10:26.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:83:0)
[12:10:26.214] Timer tick, asking Buildbucket for the build status
[12:10:26.300] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:26.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:84:0) after 1m0s
[12:11:26.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:84:0)
[12:11:26.347] Timer tick, asking Buildbucket for the build status
[12:11:26.408] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:26.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:85:0) after 1m0s
[12:12:26.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:85:0)
[12:12:26.477] Timer tick, asking Buildbucket for the build status
[12:12:26.536] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:26.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:86:0) after 1m0s
[12:13:26.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:86:0)
[12:13:26.567] Timer tick, asking Buildbucket for the build status
[12:13:26.675] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:26.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:87:0) after 1m0s
[12:14:26.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:87:0)
[12:14:26.769] Timer tick, asking Buildbucket for the build status
[12:14:26.938] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:26.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:88:0) after 1m0s
[12:15:26.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:88:0)
[12:15:26.957] Timer tick, asking Buildbucket for the build status
[12:15:27.021] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:27.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:89:0) after 1m0s
[12:16:27.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:89:0)
[12:16:27.027] Timer tick, asking Buildbucket for the build status
[12:16:27.106] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:27.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:90:0) after 1m0s
[12:17:27.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:90:0)
[12:17:27.130] Timer tick, asking Buildbucket for the build status
[12:17:27.587] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:27.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:91:0) after 1m0s
[12:18:27.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:91:0)
[12:18:27.717] Timer tick, asking Buildbucket for the build status
[12:18:27.785] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:27.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:92:0) after 1m0s
[12:19:27.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:92:0)
[12:19:27.808] Timer tick, asking Buildbucket for the build status
[12:19:28.214] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:28.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:93:0) after 1m0s
[12:20:28.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:93:0)
[12:20:28.412] Timer tick, asking Buildbucket for the build status
[12:20:28.546] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:28.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:94:0) after 1m0s
[12:21:28.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:94:0)
[12:21:28.575] Timer tick, asking Buildbucket for the build status
[12:21:28.635] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:28.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:95:0) after 1m0s
[12:22:28.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:95:0)
[12:22:28.662] Timer tick, asking Buildbucket for the build status
[12:22:28.771] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:28.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:96:0) after 1m0s
[12:23:28.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:96:0)
[12:23:28.847] Timer tick, asking Buildbucket for the build status
[12:23:29.007] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:29.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:97:0) after 1m0s
[12:24:29.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:97:0)
[12:24:29.123] Timer tick, asking Buildbucket for the build status
[12:24:29.276] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:29.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:98:0) after 1m0s
[12:25:29.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:98:0)
[12:25:29.348] Timer tick, asking Buildbucket for the build status
[12:25:29.420] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:29.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:99:0) after 1m0s
[12:26:29.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:99:0)
[12:26:29.509] Timer tick, asking Buildbucket for the build status
[12:26:29.913] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:29.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:100:0) after 1m0s
[12:27:29.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:100:0)
[12:27:29.955] Timer tick, asking Buildbucket for the build status
[12:27:30.029] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:30.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:101:0) after 1m0s
[12:28:30.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:101:0)
[12:28:30.007] Timer tick, asking Buildbucket for the build status
[12:28:30.069] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:30.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:102:0) after 1m0s
[12:29:30.137] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:102:0)
[12:29:30.137] Timer tick, asking Buildbucket for the build status
[12:29:30.282] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:30.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:103:0) after 1m0s
[12:30:30.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:103:0)
[12:30:30.305] Timer tick, asking Buildbucket for the build status
[12:30:30.365] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:30.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:104:0) after 1m0s
[12:31:30.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:104:0)
[12:31:30.441] Timer tick, asking Buildbucket for the build status
[12:31:30.510] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:30.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:105:0) after 1m0s
[12:32:30.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:105:0)
[12:32:30.585] Timer tick, asking Buildbucket for the build status
[12:32:30.814] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:30.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:106:0) after 1m0s
[12:33:30.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:106:0)
[12:33:30.842] Timer tick, asking Buildbucket for the build status
[12:33:30.923] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:30.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:107:0) after 1m0s
[12:34:30.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:107:0)
[12:34:30.951] Timer tick, asking Buildbucket for the build status
[12:34:31.319] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:31.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:108:0) after 1m0s
[12:35:31.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:108:0)
[12:35:31.426] Timer tick, asking Buildbucket for the build status
[12:35:31.722] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:31.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:109:0) after 1m0s
[12:36:31.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:109:0)
[12:36:31.858] Timer tick, asking Buildbucket for the build status
[12:36:31.969] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:31.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:110:0) after 1m0s
[12:37:32.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:110:0)
[12:37:32.041] Timer tick, asking Buildbucket for the build status
[12:37:32.102] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:32.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:111:0) after 1m0s
[12:38:32.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:111:0)
[12:38:32.117] Timer tick, asking Buildbucket for the build status
[12:38:32.183] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:32.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:112:0) after 1m0s
[12:39:32.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:112:0)
[12:39:32.267] Timer tick, asking Buildbucket for the build status
[12:39:32.775] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:32.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:113:0) after 1m0s
[12:40:32.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:113:0)
[12:40:32.790] Timer tick, asking Buildbucket for the build status
[12:40:32.862] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:32.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:114:0) after 1m0s
[12:41:32.981] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:114:0)
[12:41:32.981] Timer tick, asking Buildbucket for the build status
[12:41:33.047] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:33.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:115:0) after 1m0s
[12:42:33.070] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:115:0)
[12:42:33.070] Timer tick, asking Buildbucket for the build status
[12:42:33.268] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:33.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:116:0) after 1m0s
[12:43:33.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:116:0)
[12:43:33.475] Timer tick, asking Buildbucket for the build status
[12:43:33.583] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:33.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:117:0) after 1m0s
[12:44:33.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:117:0)
[12:44:33.655] Timer tick, asking Buildbucket for the build status
[12:44:33.774] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:33.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:118:0) after 1m0s
[12:45:33.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:118:0)
[12:45:33.955] Timer tick, asking Buildbucket for the build status
[12:45:34.388] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:34.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:119:0) after 1m0s
[12:46:34.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:119:0)
[12:46:34.412] Timer tick, asking Buildbucket for the build status
[12:46:34.465] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:34.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:120:0) after 1m0s
[12:47:41.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:120:0)
[12:47:41.555] Timer tick, asking Buildbucket for the build status
[12:47:41.642] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:41.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:121:0) after 1m0s
[12:48:41.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:121:0)
[12:48:41.662] Timer tick, asking Buildbucket for the build status
[12:48:41.732] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:41.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:122:0) after 1m0s
[12:49:41.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:122:0)
[12:49:41.804] Timer tick, asking Buildbucket for the build status
[12:49:41.924] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:41.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:123:0) after 1m0s
[12:50:41.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:123:0)
[12:50:41.948] Timer tick, asking Buildbucket for the build status
[12:50:42.308] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:42.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:124:0) after 1m0s
[12:51:42.325] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:124:0)
[12:51:42.325] Timer tick, asking Buildbucket for the build status
[12:51:42.440] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:42.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:125:0) after 1m0s
[12:52:42.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:125:0)
[12:52:42.467] Timer tick, asking Buildbucket for the build status
[12:52:42.532] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:42.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:126:0) after 1m0s
[12:53:42.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:126:0)
[12:53:42.571] Timer tick, asking Buildbucket for the build status
[12:53:42.636] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:42.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:127:0) after 1m0s
[12:54:42.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:127:0)
[12:54:42.701] Timer tick, asking Buildbucket for the build status
[12:54:42.807] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:42.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:128:0) after 1m0s
[12:55:42.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:128:0)
[12:55:42.833] Timer tick, asking Buildbucket for the build status
[12:55:43.021] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:43.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:129:0) after 1m0s
[12:56:43.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:129:0)
[12:56:43.088] Timer tick, asking Buildbucket for the build status
[12:56:43.185] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:43.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:130:0) after 1m0s
[12:57:43.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:130:0)
[12:57:43.223] Timer tick, asking Buildbucket for the build status
[12:57:43.286] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:43.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:131:0) after 1m0s
[12:58:43.343] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:131:0)
[12:58:43.343] Timer tick, asking Buildbucket for the build status
[12:58:43.447] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:132:0) after 1m0s
[12:59:43.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:132:0)
[12:59:43.486] Timer tick, asking Buildbucket for the build status
[12:59:43.627] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:43.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:133:0) after 1m0s
[13:00:43.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:133:0)
[13:00:43.747] Timer tick, asking Buildbucket for the build status
[13:00:43.822] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:43.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:134:0) after 1m0s
[13:01:43.918] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:134:0)
[13:01:43.918] Timer tick, asking Buildbucket for the build status
[13:01:43.989] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:43.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:135:0) after 1m0s
[13:02:43.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:135:0)
[13:02:43.988] Timer tick, asking Buildbucket for the build status
[13:02:44.172] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:44.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:136:0) after 1m0s
[13:03:44.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:136:0)
[13:03:44.253] Timer tick, asking Buildbucket for the build status
[13:03:44.325] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:44.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:137:0) after 1m0s
[13:04:44.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:137:0)
[13:04:44.342] Timer tick, asking Buildbucket for the build status
[13:04:44.703] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:44.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:138:0) after 1m0s
[13:05:44.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:138:0)
[13:05:44.797] Timer tick, asking Buildbucket for the build status
[13:05:45.089] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:45.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:139:0) after 1m0s
[13:06:45.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:139:0)
[13:06:45.123] Timer tick, asking Buildbucket for the build status
[13:06:45.216] Build 8866741547226077120: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:45.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032171609353594800:140:0) after 1m0s
[13:07:08.616] Received PubSub notification, asking Buildbucket for the build status
[13:07:08.801] Build 8866741547226077120: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:07:08.801] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1602421628048965",
"created_by": "project:chromium",
"created_ts": "1602413338406778",
"id": "8866741547226077120",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"99158b00290cf76b25389a20761d0ccf562f410f\"}}",
"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\": \"99158b00290cf76b25389a20761d0ccf562f410f\", \"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-8-379q\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 789, \"got_angle_revision\": \"3345e5fdc5c4360c770ddad6260dd10f80dbd762\", \"got_dawn_revision\": \"1c25198384e7ecfebbd3ac84203d3b1d46a0e228\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"99158b00290cf76b25389a20761d0ccf562f410f\", \"got_revision_cp\": \"refs/heads/master@{#815994}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"cca740fc48dfffbe24a5ee0bfe23ed1ec2b21580\", \"got_v8_revision\": \"07b0b1dcde4a99294b8028d83f4ea244885cc091\", \"got_v8_revision_cp\": \"refs/heads/8.8.62@{#1}\", \"got_webrtc_revision\": \"3326535126e435f1ba647885ce43a8f0f3d317eb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32375}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"99158b00290cf76b25389a20761d0ccf562f410f\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-379q\"], \"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\": [\"5570-fa4adf0\"], \"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": "1602413400085997",
"status": "COMPLETED",
"status_changed_ts": "1602421628390102",
"tags": [
"build_address:luci.chromium.ci/win-official/789",
"builder:win-official",
"buildset:commit/git/99158b00290cf76b25389a20761d0ccf562f410f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99158b00290cf76b25389a20761d0ccf562f410f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032171609353594800",
"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/8866741547226077120/+/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:4f3018a9cc353110",
"user_agent:luci-scheduler"
],
"updated_ts": "1602421628394921",
"url": "https://ci.chromium.org/b/8866741547226077120",
"utcnow_ts": "1602421628786161"
}
}
[13:07:08.801] Invocation finished in 2h18m11.808427555s with status SUCCEEDED