[22:53:49.725] New invocation is queued and will start shortly
[22:53:51.477] Starting the invocation (attempt 1)
[22:53:51.517] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:53:51.517] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:53:51.517] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9032669585739378496",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f4c11e334713ef505b0f75cefa8d2c4b9f65678f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9032669585739378496",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/f4c11e334713ef505b0f75cefa8d2c4b9f65678f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4c11e334713ef505b0f75cefa8d2c4b9f65678f",
"gitiles_ref:refs/heads/master"
]
}
[22:53:52.299] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1601938431618213",
"id": "8867239523086078096",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4c11e334713ef505b0f75cefa8d2c4b9f65678f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1601938432158993",
"tags": [
"build_address:luci.chromium.ci/win-official/746",
"builder:win-official",
"buildset:commit/git/f4c11e334713ef505b0f75cefa8d2c4b9f65678f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4c11e334713ef505b0f75cefa8d2c4b9f65678f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032669585739378496",
"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/8867239523086078096/+/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": "1601938432159100",
"url": "https://ci.chromium.org/b/8867239523086078096",
"utcnow_ts": "1601938432289054"
}
}
[22:53:52.299] Task URL: https://ci.chromium.org/b/8867239523086078096
[22:53:52.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:2:0) after 1m0s
[22:54:14.076] Received PubSub notification, asking Buildbucket for the build status
[22:54:14.149] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:52.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:2:0)
[22:54:52.888] Timer tick, asking Buildbucket for the build status
[22:54:52.995] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:52.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:4:0) after 1m0s
[22:55:53.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:4:0)
[22:55:53.225] Timer tick, asking Buildbucket for the build status
[22:55:53.302] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:53.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:5:0) after 1m0s
[22:56:53.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:5:0)
[22:56:53.372] Timer tick, asking Buildbucket for the build status
[22:56:53.918] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:53.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:6:0) after 1m0s
[22:57:54.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:6:0)
[22:57:54.151] Timer tick, asking Buildbucket for the build status
[22:57:54.228] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:54.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:7:0) after 1m0s
[22:58:54.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:7:0)
[22:58:54.345] Timer tick, asking Buildbucket for the build status
[22:58:54.455] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:54.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:8:0) after 1m0s
[22:59:54.679] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:8:0)
[22:59:54.679] Timer tick, asking Buildbucket for the build status
[22:59:54.784] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:54.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:9:0) after 1m0s
[23:00:54.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:9:0)
[23:00:54.984] Timer tick, asking Buildbucket for the build status
[23:00:55.066] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:55.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:10:0) after 1m0s
[23:01:55.175] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:10:0)
[23:01:55.175] Timer tick, asking Buildbucket for the build status
[23:01:55.263] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:55.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:11:0) after 1m0s
[23:02:55.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:11:0)
[23:02:55.315] Timer tick, asking Buildbucket for the build status
[23:02:55.400] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:55.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:12:0) after 1m0s
[23:03:55.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:12:0)
[23:03:55.797] Timer tick, asking Buildbucket for the build status
[23:03:55.941] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:55.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:13:0) after 1m0s
[23:04:56.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:13:0)
[23:04:56.076] Timer tick, asking Buildbucket for the build status
[23:04:56.182] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:56.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:14:0) after 1m0s
[23:05:56.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:14:0)
[23:05:56.304] Timer tick, asking Buildbucket for the build status
[23:05:56.435] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:56.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:15:0) after 1m0s
[23:06:56.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:15:0)
[23:06:56.528] Timer tick, asking Buildbucket for the build status
[23:06:56.695] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:56.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:16:0) after 1m0s
[23:07:56.833] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:16:0)
[23:07:56.833] Timer tick, asking Buildbucket for the build status
[23:07:56.909] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:56.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:17:0) after 1m0s
[23:08:56.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:17:0)
[23:08:56.982] Timer tick, asking Buildbucket for the build status
[23:08:57.260] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:57.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:18:0) after 1m0s
[23:09:57.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:18:0)
[23:09:57.307] Timer tick, asking Buildbucket for the build status
[23:09:57.438] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:57.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:19:0) after 1m0s
[23:10:57.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:19:0)
[23:10:57.517] Timer tick, asking Buildbucket for the build status
[23:10:57.973] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:57.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:20:0) after 1m0s
[23:11:57.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:20:0)
[23:11:57.976] Timer tick, asking Buildbucket for the build status
[23:11:58.329] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:58.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:21:0) after 1m0s
[23:12:58.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:21:0)
[23:12:58.487] Timer tick, asking Buildbucket for the build status
[23:12:58.706] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:58.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:22:0) after 1m0s
[23:13:58.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:22:0)
[23:13:58.855] Timer tick, asking Buildbucket for the build status
[23:13:59.053] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:59.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:23:0) after 1m0s
[23:14:59.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:23:0)
[23:14:59.071] Timer tick, asking Buildbucket for the build status
[23:14:59.235] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:59.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:24:0) after 1m0s
[23:15:59.308] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:24:0)
[23:15:59.308] Timer tick, asking Buildbucket for the build status
[23:15:59.377] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:59.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:25:0) after 1m0s
[23:16:59.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:25:0)
[23:16:59.572] Timer tick, asking Buildbucket for the build status
[23:17:00.158] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:00.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:26:0) after 1m0s
[23:18:00.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:26:0)
[23:18:00.216] Timer tick, asking Buildbucket for the build status
[23:18:00.356] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:00.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:27:0) after 1m0s
[23:19:00.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:27:0)
[23:19:00.422] Timer tick, asking Buildbucket for the build status
[23:19:00.832] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:00.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:28:0) after 1m0s
[23:20:00.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:28:0)
[23:20:00.964] Timer tick, asking Buildbucket for the build status
[23:20:01.137] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:01.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:29:0) after 1m0s
[23:21:02.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:29:0)
[23:21:02.328] Timer tick, asking Buildbucket for the build status
[23:21:02.429] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:02.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:30:0) after 1m0s
[23:22:03.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:30:0)
[23:22:03.152] Timer tick, asking Buildbucket for the build status
[23:22:03.496] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:03.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:31:0) after 1m0s
[23:23:03.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:31:0)
[23:23:03.926] Timer tick, asking Buildbucket for the build status
[23:23:04.244] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:04.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:32:0) after 1m0s
[23:24:04.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:32:0)
[23:24:04.281] Timer tick, asking Buildbucket for the build status
[23:24:04.749] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:04.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:33:0) after 1m0s
[23:25:05.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:33:0)
[23:25:05.436] Timer tick, asking Buildbucket for the build status
[23:25:05.525] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:05.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:34:0) after 1m0s
[23:26:05.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:34:0)
[23:26:05.558] Timer tick, asking Buildbucket for the build status
[23:26:05.634] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:05.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:35:0) after 1m0s
[23:27:05.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:35:0)
[23:27:05.686] Timer tick, asking Buildbucket for the build status
[23:27:05.766] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:05.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:36:0) after 1m0s
[23:28:05.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:36:0)
[23:28:05.793] Timer tick, asking Buildbucket for the build status
[23:28:05.915] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:05.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:37:0) after 1m0s
[23:29:05.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:37:0)
[23:29:05.941] Timer tick, asking Buildbucket for the build status
[23:29:06.600] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:06.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:38:0) after 1m0s
[23:30:06.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:38:0)
[23:30:06.601] Timer tick, asking Buildbucket for the build status
[23:30:06.846] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:06.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:39:0) after 1m0s
[23:31:06.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:39:0)
[23:31:06.913] Timer tick, asking Buildbucket for the build status
[23:31:07.306] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:07.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:40:0) after 1m0s
[23:32:07.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:40:0)
[23:32:07.402] Timer tick, asking Buildbucket for the build status
[23:32:07.509] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:07.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:41:0) after 1m0s
[23:33:07.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:41:0)
[23:33:07.529] Timer tick, asking Buildbucket for the build status
[23:33:07.838] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:07.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:42:0) after 1m0s
[23:34:07.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:42:0)
[23:34:07.864] Timer tick, asking Buildbucket for the build status
[23:34:07.970] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:07.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:43:0) after 1m0s
[23:35:08.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:43:0)
[23:35:08.041] Timer tick, asking Buildbucket for the build status
[23:35:08.360] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:08.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:44:0) after 1m0s
[23:36:08.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:44:0)
[23:36:08.501] Timer tick, asking Buildbucket for the build status
[23:36:08.588] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:08.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:45:0) after 1m0s
[23:37:08.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:45:0)
[23:37:08.613] Timer tick, asking Buildbucket for the build status
[23:37:08.672] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:08.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:46:0) after 1m0s
[23:38:08.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:46:0)
[23:38:08.699] Timer tick, asking Buildbucket for the build status
[23:38:08.781] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:08.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:47:0) after 1m0s
[23:39:08.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:47:0)
[23:39:08.761] Timer tick, asking Buildbucket for the build status
[23:39:08.897] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:08.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:48:0) after 1m0s
[23:40:08.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:48:0)
[23:40:08.933] Timer tick, asking Buildbucket for the build status
[23:40:09.140] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:09.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:49:0) after 1m0s
[23:41:09.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:49:0)
[23:41:09.176] Timer tick, asking Buildbucket for the build status
[23:41:11.745] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:11.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:50:0) after 1m0s
[23:42:11.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:50:0)
[23:42:11.855] Timer tick, asking Buildbucket for the build status
[23:42:11.946] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:11.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:51:0) after 1m0s
[23:43:12.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:51:0)
[23:43:12.036] Timer tick, asking Buildbucket for the build status
[23:43:12.121] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:12.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:52:0) after 1m0s
[23:44:12.156] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:52:0)
[23:44:12.156] Timer tick, asking Buildbucket for the build status
[23:44:12.624] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:12.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:53:0) after 1m0s
[23:45:12.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:53:0)
[23:45:12.666] Timer tick, asking Buildbucket for the build status
[23:45:12.735] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:12.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:54:0) after 1m0s
[23:46:12.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:54:0)
[23:46:12.788] Timer tick, asking Buildbucket for the build status
[23:46:12.866] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:12.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:55:0) after 1m0s
[23:47:12.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:55:0)
[23:47:12.902] Timer tick, asking Buildbucket for the build status
[23:47:12.963] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:12.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:56:0) after 1m0s
[23:48:13.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:56:0)
[23:48:13.074] Timer tick, asking Buildbucket for the build status
[23:48:13.162] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:13.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:57:0) after 1m0s
[23:49:13.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:57:0)
[23:49:13.304] Timer tick, asking Buildbucket for the build status
[23:49:13.448] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:13.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:58:0) after 1m0s
[23:50:13.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:58:0)
[23:50:13.441] Timer tick, asking Buildbucket for the build status
[23:50:13.673] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:13.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:59:0) after 1m0s
[23:51:13.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:59:0)
[23:51:13.716] Timer tick, asking Buildbucket for the build status
[23:51:13.776] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:13.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:60:0) after 1m0s
[23:52:13.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:60:0)
[23:52:13.825] Timer tick, asking Buildbucket for the build status
[23:52:13.918] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:13.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:61:0) after 1m0s
[23:53:13.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:61:0)
[23:53:13.934] Timer tick, asking Buildbucket for the build status
[23:53:13.993] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:13.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:62:0) after 1m0s
[23:54:14.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:62:0)
[23:54:14.087] Timer tick, asking Buildbucket for the build status
[23:54:14.374] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:14.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:63:0) after 1m0s
[23:55:14.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:63:0)
[23:55:14.423] Timer tick, asking Buildbucket for the build status
[23:55:14.504] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:14.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:64:0) after 1m0s
[23:56:14.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:64:0)
[23:56:14.592] Timer tick, asking Buildbucket for the build status
[23:56:14.691] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:14.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:65:0) after 1m0s
[23:57:14.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:65:0)
[23:57:14.719] Timer tick, asking Buildbucket for the build status
[23:57:14.823] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:14.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:66:0) after 1m0s
[23:58:14.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:66:0)
[23:58:14.873] Timer tick, asking Buildbucket for the build status
[23:58:14.984] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:14.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:67:0) after 1m0s
[23:59:15.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:67:0)
[23:59:15.006] Timer tick, asking Buildbucket for the build status
[23:59:15.401] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:15.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:68:0) after 1m0s
[00:00:15.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:68:0)
[00:00:15.471] Timer tick, asking Buildbucket for the build status
[00:00:16.674] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:16.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:69:0) after 1m0s
[00:01:17.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:69:0)
[00:01:17.056] Timer tick, asking Buildbucket for the build status
[00:01:18.156] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:18.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:70:0) after 1m0s
[00:02:18.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:70:0)
[00:02:18.183] Timer tick, asking Buildbucket for the build status
[00:02:18.247] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:18.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:71:0) after 1m0s
[00:03:18.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:71:0)
[00:03:18.287] Timer tick, asking Buildbucket for the build status
[00:03:18.349] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:18.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:72:0) after 1m0s
[00:04:18.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:72:0)
[00:04:18.360] Timer tick, asking Buildbucket for the build status
[00:04:18.522] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:18.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:73:0) after 1m0s
[00:05:18.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:73:0)
[00:05:18.588] Timer tick, asking Buildbucket for the build status
[00:05:18.754] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:18.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:74:0) after 1m0s
[00:06:18.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:74:0)
[00:06:18.737] Timer tick, asking Buildbucket for the build status
[00:06:20.219] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:20.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:75:0) after 1m0s
[00:07:20.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:75:0)
[00:07:20.256] Timer tick, asking Buildbucket for the build status
[00:07:20.324] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:20.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:76:0) after 1m0s
[00:08:20.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:76:0)
[00:08:20.384] Timer tick, asking Buildbucket for the build status
[00:08:20.551] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:20.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:77:0) after 1m0s
[00:09:20.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:77:0)
[00:09:20.553] Timer tick, asking Buildbucket for the build status
[00:09:20.989] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:20.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:78:0) after 1m0s
[00:10:21.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:78:0)
[00:10:21.043] Timer tick, asking Buildbucket for the build status
[00:10:21.361] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:21.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:79:0) after 1m0s
[00:11:21.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:79:0)
[00:11:21.402] Timer tick, asking Buildbucket for the build status
[00:11:21.495] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:21.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:80:0) after 1m0s
[00:12:21.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:80:0)
[00:12:21.483] Timer tick, asking Buildbucket for the build status
[00:12:21.556] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:21.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:81:0) after 1m0s
[00:13:21.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:81:0)
[00:13:21.600] Timer tick, asking Buildbucket for the build status
[00:13:21.671] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:21.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:82:0) after 1m0s
[00:14:21.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:82:0)
[00:14:21.657] Timer tick, asking Buildbucket for the build status
[00:14:22.038] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:22.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:83:0) after 1m0s
[00:15:22.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:83:0)
[00:15:22.167] Timer tick, asking Buildbucket for the build status
[00:15:22.226] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:22.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:84:0) after 1m0s
[00:16:22.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:84:0)
[00:16:22.262] Timer tick, asking Buildbucket for the build status
[00:16:22.334] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:22.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:85:0) after 1m0s
[00:17:22.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:85:0)
[00:17:22.410] Timer tick, asking Buildbucket for the build status
[00:17:22.461] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:22.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:86:0) after 1m0s
[00:18:22.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:86:0)
[00:18:22.509] Timer tick, asking Buildbucket for the build status
[00:18:22.615] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:22.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:87:0) after 1m0s
[00:19:22.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:87:0)
[00:19:22.642] Timer tick, asking Buildbucket for the build status
[00:19:22.762] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:22.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:88:0) after 1m0s
[00:20:22.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:88:0)
[00:20:22.786] Timer tick, asking Buildbucket for the build status
[00:20:22.854] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:22.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:89:0) after 1m0s
[00:21:22.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:89:0)
[00:21:22.852] Timer tick, asking Buildbucket for the build status
[00:21:22.938] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:22.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:90:0) after 1m0s
[00:22:22.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:90:0)
[00:22:22.935] Timer tick, asking Buildbucket for the build status
[00:22:23.068] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:23.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:91:0) after 1m0s
[00:23:23.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:91:0)
[00:23:23.120] Timer tick, asking Buildbucket for the build status
[00:23:23.482] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:23.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:92:0) after 1m0s
[00:24:23.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:92:0)
[00:24:23.526] Timer tick, asking Buildbucket for the build status
[00:24:24.515] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:24.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:93:0) after 1m0s
[00:25:24.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:93:0)
[00:25:24.678] Timer tick, asking Buildbucket for the build status
[00:25:24.759] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:24.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:94:0) after 1m0s
[00:26:24.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:94:0)
[00:26:24.828] Timer tick, asking Buildbucket for the build status
[00:26:24.895] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:24.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:95:0) after 1m0s
[00:27:24.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:95:0)
[00:27:24.955] Timer tick, asking Buildbucket for the build status
[00:27:25.201] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:25.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:96:0) after 1m0s
[00:28:25.226] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:96:0)
[00:28:25.226] Timer tick, asking Buildbucket for the build status
[00:28:25.363] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:25.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:97:0) after 1m0s
[00:29:25.446] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:97:0)
[00:29:25.446] Timer tick, asking Buildbucket for the build status
[00:29:25.572] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:25.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:98:0) after 1m0s
[00:30:25.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:98:0)
[00:30:25.671] Timer tick, asking Buildbucket for the build status
[00:30:25.762] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:25.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:99:0) after 1m0s
[00:31:25.810] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:99:0)
[00:31:25.810] Timer tick, asking Buildbucket for the build status
[00:31:26.134] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:26.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:100:0) after 1m0s
[00:32:26.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:100:0)
[00:32:26.212] Timer tick, asking Buildbucket for the build status
[00:32:26.457] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:26.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:101:0) after 1m0s
[00:33:26.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:101:0)
[00:33:26.575] Timer tick, asking Buildbucket for the build status
[00:33:26.643] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:26.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:102:0) after 1m0s
[00:34:26.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:102:0)
[00:34:26.700] Timer tick, asking Buildbucket for the build status
[00:34:26.841] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:26.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:103:0) after 1m0s
[00:35:26.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:103:0)
[00:35:26.945] Timer tick, asking Buildbucket for the build status
[00:35:27.013] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:27.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:104:0) after 1m0s
[00:36:27.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:104:0)
[00:36:27.120] Timer tick, asking Buildbucket for the build status
[00:36:27.191] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:27.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:105:0) after 1m0s
[00:37:27.300] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:105:0)
[00:37:27.300] Timer tick, asking Buildbucket for the build status
[00:37:27.510] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:27.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:106:0) after 1m0s
[00:38:27.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:106:0)
[00:38:27.545] Timer tick, asking Buildbucket for the build status
[00:38:28.272] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:28.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:107:0) after 1m0s
[00:39:28.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:107:0)
[00:39:28.326] Timer tick, asking Buildbucket for the build status
[00:39:28.576] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:28.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:108:0) after 1m0s
[00:40:28.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:108:0)
[00:40:28.597] Timer tick, asking Buildbucket for the build status
[00:40:29.054] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:29.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:109:0) after 1m0s
[00:41:29.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:109:0)
[00:41:29.135] Timer tick, asking Buildbucket for the build status
[00:41:29.202] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:29.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:110:0) after 1m0s
[00:42:29.213] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:110:0)
[00:42:29.213] Timer tick, asking Buildbucket for the build status
[00:42:29.491] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:29.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:111:0) after 1m0s
[00:43:29.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:111:0)
[00:43:29.546] Timer tick, asking Buildbucket for the build status
[00:43:29.665] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:29.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:112:0) after 1m0s
[00:44:29.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:112:0)
[00:44:29.696] Timer tick, asking Buildbucket for the build status
[00:44:29.919] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:29.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:113:0) after 1m0s
[00:45:29.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:113:0)
[00:45:29.957] Timer tick, asking Buildbucket for the build status
[00:45:30.042] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:30.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:114:0) after 1m0s
[00:46:30.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:114:0)
[00:46:30.081] Timer tick, asking Buildbucket for the build status
[00:46:30.145] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:30.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:115:0) after 1m0s
[00:47:30.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:115:0)
[00:47:30.225] Timer tick, asking Buildbucket for the build status
[00:47:30.500] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:30.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:116:0) after 1m0s
[00:48:30.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:116:0)
[00:48:30.547] Timer tick, asking Buildbucket for the build status
[00:48:30.609] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:30.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:117:0) after 1m0s
[00:49:30.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:117:0)
[00:49:30.730] Timer tick, asking Buildbucket for the build status
[00:49:30.854] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:30.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:118:0) after 1m0s
[00:50:30.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:118:0)
[00:50:30.920] Timer tick, asking Buildbucket for the build status
[00:50:30.983] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:30.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:119:0) after 1m0s
[00:51:31.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:119:0)
[00:51:31.066] Timer tick, asking Buildbucket for the build status
[00:51:31.172] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:31.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:120:0) after 1m0s
[00:52:31.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:120:0)
[00:52:31.182] Timer tick, asking Buildbucket for the build status
[00:52:31.314] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:31.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:121:0) after 1m0s
[00:53:31.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:121:0)
[00:53:31.312] Timer tick, asking Buildbucket for the build status
[00:53:31.370] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:31.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:122:0) after 1m0s
[00:54:31.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:122:0)
[00:54:31.359] Timer tick, asking Buildbucket for the build status
[00:54:31.459] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:31.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:123:0) after 1m0s
[00:55:31.461] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:123:0)
[00:55:31.461] Timer tick, asking Buildbucket for the build status
[00:55:31.596] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:31.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:124:0) after 1m0s
[00:56:31.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:124:0)
[00:56:31.693] Timer tick, asking Buildbucket for the build status
[00:56:31.787] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:31.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:125:0) after 1m0s
[00:57:31.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:125:0)
[00:57:31.838] Timer tick, asking Buildbucket for the build status
[00:57:31.935] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:31.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:126:0) after 1m0s
[00:58:32.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:126:0)
[00:58:32.075] Timer tick, asking Buildbucket for the build status
[00:58:32.600] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:32.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:127:0) after 1m0s
[00:59:34.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:127:0)
[00:59:34.153] Timer tick, asking Buildbucket for the build status
[00:59:34.292] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:34.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:128:0) after 1m0s
[01:00:34.367] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:128:0)
[01:00:34.367] Timer tick, asking Buildbucket for the build status
[01:00:34.442] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:34.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:129:0) after 1m0s
[01:01:34.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:129:0)
[01:01:34.460] Timer tick, asking Buildbucket for the build status
[01:01:34.818] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:34.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:130:0) after 1m0s
[01:02:34.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:130:0)
[01:02:34.842] Timer tick, asking Buildbucket for the build status
[01:02:34.905] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:34.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:131:0) after 1m0s
[01:03:35.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:131:0)
[01:03:35.014] Timer tick, asking Buildbucket for the build status
[01:03:35.122] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:35.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:132:0) after 1m0s
[01:04:35.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:132:0)
[01:04:35.243] Timer tick, asking Buildbucket for the build status
[01:04:35.411] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:35.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:133:0) after 1m0s
[01:05:35.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:133:0)
[01:05:35.504] Timer tick, asking Buildbucket for the build status
[01:05:35.562] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:35.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:134:0) after 1m0s
[01:06:35.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:134:0)
[01:06:35.765] Timer tick, asking Buildbucket for the build status
[01:06:35.828] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:35.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:135:0) after 1m0s
[01:07:36.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:135:0)
[01:07:36.008] Timer tick, asking Buildbucket for the build status
[01:07:36.274] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:36.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:136:0) after 1m0s
[01:08:36.337] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:136:0)
[01:08:36.337] Timer tick, asking Buildbucket for the build status
[01:08:36.536] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:36.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:137:0) after 1m0s
[01:09:36.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:137:0)
[01:09:36.579] Timer tick, asking Buildbucket for the build status
[01:09:36.703] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:36.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:138:0) after 1m0s
[01:10:36.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:138:0)
[01:10:36.758] Timer tick, asking Buildbucket for the build status
[01:10:36.918] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:36.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:139:0) after 1m0s
[01:11:36.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:139:0)
[01:11:36.920] Timer tick, asking Buildbucket for the build status
[01:11:37.001] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:37.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:140:0) after 1m0s
[01:12:37.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:140:0)
[01:12:37.023] Timer tick, asking Buildbucket for the build status
[01:12:37.095] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:37.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:141:0) after 1m0s
[01:13:37.162] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:141:0)
[01:13:37.162] Timer tick, asking Buildbucket for the build status
[01:13:37.250] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:37.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:142:0) after 1m0s
[01:14:37.310] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:142:0)
[01:14:37.310] Timer tick, asking Buildbucket for the build status
[01:14:37.425] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:37.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:143:0) after 1m0s
[01:15:37.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:143:0)
[01:15:37.524] Timer tick, asking Buildbucket for the build status
[01:15:37.626] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:37.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:144:0) after 1m0s
[01:16:37.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:144:0)
[01:16:37.821] Timer tick, asking Buildbucket for the build status
[01:16:37.901] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:37.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:145:0) after 1m0s
[01:17:38.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:145:0)
[01:17:38.192] Timer tick, asking Buildbucket for the build status
[01:17:38.269] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:38.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:146:0) after 1m0s
[01:18:38.377] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:146:0)
[01:18:38.377] Timer tick, asking Buildbucket for the build status
[01:18:38.650] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:38.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:147:0) after 1m0s
[01:19:38.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:147:0)
[01:19:38.717] Timer tick, asking Buildbucket for the build status
[01:19:39.088] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:39.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:148:0) after 1m0s
[01:20:39.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:148:0)
[01:20:39.223] Timer tick, asking Buildbucket for the build status
[01:20:39.301] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:39.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:149:0) after 1m0s
[01:21:39.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:149:0)
[01:21:39.391] Timer tick, asking Buildbucket for the build status
[01:21:40.557] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:40.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:150:0) after 1m0s
[01:22:40.617] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:150:0)
[01:22:40.617] Timer tick, asking Buildbucket for the build status
[01:22:41.680] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:41.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:151:0) after 1m0s
[01:23:41.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:151:0)
[01:23:41.788] Timer tick, asking Buildbucket for the build status
[01:23:41.951] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:41.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:152:0) after 1m0s
[01:24:42.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:152:0)
[01:24:42.124] Timer tick, asking Buildbucket for the build status
[01:24:42.368] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:42.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:153:0) after 1m0s
[01:25:42.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:153:0)
[01:25:42.471] Timer tick, asking Buildbucket for the build status
[01:25:43.945] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:43.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:154:0) after 1m0s
[01:26:44.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:154:0)
[01:26:44.008] Timer tick, asking Buildbucket for the build status
[01:26:44.102] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:44.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:155:0) after 1m0s
[01:27:44.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:155:0)
[01:27:44.233] Timer tick, asking Buildbucket for the build status
[01:27:44.420] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:44.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:156:0) after 1m0s
[01:28:44.494] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:156:0)
[01:28:44.494] Timer tick, asking Buildbucket for the build status
[01:28:44.586] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:44.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:157:0) after 1m0s
[01:29:44.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:157:0)
[01:29:44.688] Timer tick, asking Buildbucket for the build status
[01:29:44.855] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:44.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:158:0) after 1m0s
[01:30:44.880] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:158:0)
[01:30:44.880] Timer tick, asking Buildbucket for the build status
[01:30:44.957] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:44.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:159:0) after 1m0s
[01:31:45.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:159:0)
[01:31:45.058] Timer tick, asking Buildbucket for the build status
[01:31:45.125] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:45.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:160:0) after 1m0s
[01:32:45.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:160:0)
[01:32:45.157] Timer tick, asking Buildbucket for the build status
[01:32:45.226] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:45.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:161:0) after 1m0s
[01:33:45.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:161:0)
[01:33:45.316] Timer tick, asking Buildbucket for the build status
[01:33:45.378] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:45.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:162:0) after 1m0s
[01:34:45.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:162:0)
[01:34:45.412] Timer tick, asking Buildbucket for the build status
[01:34:45.547] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:45.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:163:0) after 1m0s
[01:35:45.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:163:0)
[01:35:45.620] Timer tick, asking Buildbucket for the build status
[01:35:45.898] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:45.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:164:0) after 1m0s
[01:36:45.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:164:0)
[01:36:45.933] Timer tick, asking Buildbucket for the build status
[01:36:46.001] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:46.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:165:0) after 1m0s
[01:37:46.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:165:0)
[01:37:46.065] Timer tick, asking Buildbucket for the build status
[01:37:46.117] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:46.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:166:0) after 1m0s
[01:38:46.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:166:0)
[01:38:46.183] Timer tick, asking Buildbucket for the build status
[01:38:46.372] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:46.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:167:0) after 1m0s
[01:39:46.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:167:0)
[01:39:46.530] Timer tick, asking Buildbucket for the build status
[01:39:46.652] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:46.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:168:0) after 1m0s
[01:40:46.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:168:0)
[01:40:46.799] Timer tick, asking Buildbucket for the build status
[01:40:46.879] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:46.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:169:0) after 1m0s
[01:41:46.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:169:0)
[01:41:46.949] Timer tick, asking Buildbucket for the build status
[01:41:47.073] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:47.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:170:0) after 1m0s
[01:42:47.141] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:170:0)
[01:42:47.142] Timer tick, asking Buildbucket for the build status
[01:42:47.215] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:47.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:171:0) after 1m0s
[01:43:47.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:171:0)
[01:43:47.388] Timer tick, asking Buildbucket for the build status
[01:43:47.439] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:47.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:172:0) after 1m0s
[01:44:47.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:172:0)
[01:44:47.597] Timer tick, asking Buildbucket for the build status
[01:44:47.867] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:47.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:173:0) after 1m0s
[01:45:48.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:173:0)
[01:45:48.020] Timer tick, asking Buildbucket for the build status
[01:45:48.281] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:48.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:174:0) after 1m0s
[01:46:48.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:174:0)
[01:46:48.342] Timer tick, asking Buildbucket for the build status
[01:46:48.463] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:48.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:175:0) after 1m0s
[01:47:48.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:175:0)
[01:47:48.458] Timer tick, asking Buildbucket for the build status
[01:47:48.571] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:48.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:176:0) after 1m0s
[01:48:48.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:176:0)
[01:48:48.809] Timer tick, asking Buildbucket for the build status
[01:48:48.881] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:48.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:177:0) after 1m0s
[01:49:48.904] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:177:0)
[01:49:48.904] Timer tick, asking Buildbucket for the build status
[01:49:49.230] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:49.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:178:0) after 1m0s
[01:50:49.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:178:0)
[01:50:49.859] Timer tick, asking Buildbucket for the build status
[01:50:49.935] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:49.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:179:0) after 1m0s
[01:51:50.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:179:0)
[01:51:50.235] Timer tick, asking Buildbucket for the build status
[01:51:50.289] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:50.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:180:0) after 1m0s
[01:52:50.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:180:0)
[01:52:50.465] Timer tick, asking Buildbucket for the build status
[01:52:50.821] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:50.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:181:0) after 1m0s
[01:53:50.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:181:0)
[01:53:50.852] Timer tick, asking Buildbucket for the build status
[01:53:50.950] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:50.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:182:0) after 1m0s
[01:54:50.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:182:0)
[01:54:50.953] Timer tick, asking Buildbucket for the build status
[01:54:51.045] Build 8867239523086078096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:51.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9032669585739378496:183:0) after 1m0s
[01:55:08.772] Received PubSub notification, asking Buildbucket for the build status
[01:55:08.991] Build 8867239523086078096: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:55:08.991] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1601949307837454",
"created_by": "project:chromium",
"created_ts": "1601938431618213",
"id": "8867239523086078096",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4c11e334713ef505b0f75cefa8d2c4b9f65678f\"}}",
"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\": \"f4c11e334713ef505b0f75cefa8d2c4b9f65678f\", \"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-2-xqx6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 746, \"got_angle_revision\": \"34445b29ae408853f241346312f1ef1052abf198\", \"got_dawn_revision\": \"df909306835aaac8326dd6a06bac2efdca43ee01\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"f4c11e334713ef505b0f75cefa8d2c4b9f65678f\", \"got_revision_cp\": \"refs/heads/master@{#813976}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"afe6ca45b6da79f52d02b76bce03fc72cc322920\", \"got_v8_revision\": \"7de00dff882453ea4111f2d700e683c5b3517911\", \"got_v8_revision_cp\": \"refs/heads/8.8.20@{#1}\", \"got_webrtc_revision\": \"eacbd972ab7e85e9d61851ced57aba1eb194de3d\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32316}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f4c11e334713ef505b0f75cefa8d2c4b9f65678f\"}, \"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-2-xqx6\"], \"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\": [\"5564-e41b668\"], \"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": "1601938453604700",
"status": "COMPLETED",
"status_changed_ts": "1601949308382084",
"tags": [
"build_address:luci.chromium.ci/win-official/746",
"builder:win-official",
"buildset:commit/git/f4c11e334713ef505b0f75cefa8d2c4b9f65678f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f4c11e334713ef505b0f75cefa8d2c4b9f65678f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9032669585739378496",
"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/8867239523086078096/+/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:4f13ca329a875c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1601949308398371",
"url": "https://ci.chromium.org/b/8867239523086078096",
"utcnow_ts": "1601949308969478"
}
}
[01:55:08.991] Invocation finished in 3h1m19.287047741s with status SUCCEEDED