[10:37:55.236] New invocation is queued and will start shortly
[10:37:56.314] Starting the invocation (attempt 1)
[10:37:56.355] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:37:56.355] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:37:56.355] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9036249166762380576",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"883df4a2cb671f49635fe6e3d149e02feb4ec3f0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9036249166762380576",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/883df4a2cb671f49635fe6e3d149e02feb4ec3f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/883df4a2cb671f49635fe6e3d149e02feb4ec3f0",
"gitiles_ref:refs/heads/master"
]
}
[10:37:57.065] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1598524676441811",
"id": "8870819104834029424",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"883df4a2cb671f49635fe6e3d149e02feb4ec3f0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1598524676930465",
"tags": [
"build_address:luci.chromium.ci/win-official/435",
"builder:win-official",
"buildset:commit/git/883df4a2cb671f49635fe6e3d149e02feb4ec3f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/883df4a2cb671f49635fe6e3d149e02feb4ec3f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036249166762380576",
"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/8870819104834029424/+/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": "1598524676930589",
"url": "https://ci.chromium.org/b/8870819104834029424",
"utcnow_ts": "1598524677036923"
}
}
[10:37:57.065] Task URL: https://ci.chromium.org/b/8870819104834029424
[10:37:57.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:2:0) after 1m0s
[10:38:38.795] Received PubSub notification, asking Buildbucket for the build status
[10:38:38.886] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:57.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:2:0)
[10:38:57.199] Timer tick, asking Buildbucket for the build status
[10:38:59.289] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:59.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:4:0) after 1m0s
[10:39:59.373] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:4:0)
[10:39:59.373] Timer tick, asking Buildbucket for the build status
[10:39:59.448] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:59.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:5:0) after 1m0s
[10:40:59.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:5:0)
[10:40:59.595] Timer tick, asking Buildbucket for the build status
[10:40:59.791] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:59.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:6:0) after 1m0s
[10:41:59.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:6:0)
[10:41:59.863] Timer tick, asking Buildbucket for the build status
[10:42:00.200] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:00.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:7:0) after 1m0s
[10:43:00.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:7:0)
[10:43:00.236] Timer tick, asking Buildbucket for the build status
[10:43:00.361] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:00.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:8:0) after 1m0s
[10:44:00.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:8:0)
[10:44:00.413] Timer tick, asking Buildbucket for the build status
[10:44:00.571] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:00.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:9:0) after 1m0s
[10:45:00.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:9:0)
[10:45:00.897] Timer tick, asking Buildbucket for the build status
[10:45:00.979] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:00.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:10:0) after 1m0s
[10:46:01.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:10:0)
[10:46:01.116] Timer tick, asking Buildbucket for the build status
[10:46:01.248] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:01.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:11:0) after 1m0s
[10:47:01.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:11:0)
[10:47:01.514] Timer tick, asking Buildbucket for the build status
[10:47:01.594] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:01.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:12:0) after 1m0s
[10:48:02.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:12:0)
[10:48:02.157] Timer tick, asking Buildbucket for the build status
[10:48:02.313] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:02.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:13:0) after 1m0s
[10:49:02.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:13:0)
[10:49:02.339] Timer tick, asking Buildbucket for the build status
[10:49:02.753] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:02.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:14:0) after 1m0s
[10:50:02.882] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:14:0)
[10:50:02.882] Timer tick, asking Buildbucket for the build status
[10:50:02.981] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:02.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:15:0) after 1m0s
[10:51:03.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:15:0)
[10:51:03.317] Timer tick, asking Buildbucket for the build status
[10:51:03.445] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:03.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:16:0) after 1m0s
[10:52:03.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:16:0)
[10:52:03.492] Timer tick, asking Buildbucket for the build status
[10:52:03.672] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:03.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:17:0) after 1m0s
[10:53:03.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:17:0)
[10:53:03.695] Timer tick, asking Buildbucket for the build status
[10:53:04.083] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:04.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:18:0) after 1m0s
[10:54:04.165] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:18:0)
[10:54:04.165] Timer tick, asking Buildbucket for the build status
[10:54:04.235] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:04.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:19:0) after 1m0s
[10:55:04.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:19:0)
[10:55:04.365] Timer tick, asking Buildbucket for the build status
[10:55:05.361] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:05.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:20:0) after 1m0s
[10:56:05.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:20:0)
[10:56:05.474] Timer tick, asking Buildbucket for the build status
[10:56:05.544] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:05.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:21:0) after 1m0s
[10:57:05.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:21:0)
[10:57:05.723] Timer tick, asking Buildbucket for the build status
[10:57:05.810] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:05.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:22:0) after 1m0s
[10:58:05.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:22:0)
[10:58:05.821] Timer tick, asking Buildbucket for the build status
[10:58:05.937] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:05.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:23:0) after 1m0s
[10:59:06.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:23:0)
[10:59:06.034] Timer tick, asking Buildbucket for the build status
[10:59:06.150] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:06.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:24:0) after 1m0s
[11:00:06.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:24:0)
[11:00:06.270] Timer tick, asking Buildbucket for the build status
[11:00:06.386] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:06.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:25:0) after 1m0s
[11:01:06.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:25:0)
[11:01:06.503] Timer tick, asking Buildbucket for the build status
[11:01:06.881] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:06.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:26:0) after 1m0s
[11:02:07.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:26:0)
[11:02:07.152] Timer tick, asking Buildbucket for the build status
[11:02:07.485] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:07.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:27:0) after 1m0s
[11:03:07.755] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:27:0)
[11:03:07.755] Timer tick, asking Buildbucket for the build status
[11:03:07.959] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:07.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:28:0) after 1m0s
[11:04:08.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:28:0)
[11:04:08.040] Timer tick, asking Buildbucket for the build status
[11:04:08.152] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:08.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:29:0) after 1m0s
[11:05:08.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:29:0)
[11:05:08.209] Timer tick, asking Buildbucket for the build status
[11:05:08.294] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:08.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:30:0) after 1m0s
[11:06:08.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:30:0)
[11:06:08.356] Timer tick, asking Buildbucket for the build status
[11:06:08.430] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:08.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:31:0) after 1m0s
[11:07:08.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:31:0)
[11:07:08.512] Timer tick, asking Buildbucket for the build status
[11:07:08.578] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:08.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:32:0) after 1m0s
[11:08:08.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:32:0)
[11:08:08.607] Timer tick, asking Buildbucket for the build status
[11:08:08.757] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:08.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:33:0) after 1m0s
[11:09:08.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:33:0)
[11:09:08.802] Timer tick, asking Buildbucket for the build status
[11:09:08.999] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:08.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:34:0) after 1m0s
[11:10:09.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:34:0)
[11:10:09.019] Timer tick, asking Buildbucket for the build status
[11:10:09.127] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:09.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:35:0) after 1m0s
[11:11:09.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:35:0)
[11:11:09.155] Timer tick, asking Buildbucket for the build status
[11:11:09.262] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:09.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:36:0) after 1m0s
[11:12:09.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:36:0)
[11:12:09.281] Timer tick, asking Buildbucket for the build status
[11:12:09.429] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:09.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:37:0) after 1m0s
[11:13:09.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:37:0)
[11:13:09.475] Timer tick, asking Buildbucket for the build status
[11:13:09.605] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:09.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:38:0) after 1m0s
[11:14:09.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:38:0)
[11:14:09.630] Timer tick, asking Buildbucket for the build status
[11:14:09.705] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:09.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:39:0) after 1m0s
[11:15:09.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:39:0)
[11:15:09.720] Timer tick, asking Buildbucket for the build status
[11:15:09.848] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:09.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:40:0) after 1m0s
[11:16:09.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:40:0)
[11:16:09.983] Timer tick, asking Buildbucket for the build status
[11:16:10.095] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:10.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:41:0) after 1m0s
[11:17:10.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:41:0)
[11:17:10.187] Timer tick, asking Buildbucket for the build status
[11:17:10.349] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:10.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:42:0) after 1m0s
[11:18:10.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:42:0)
[11:18:10.386] Timer tick, asking Buildbucket for the build status
[11:18:11.604] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:11.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:43:0) after 1m0s
[11:19:11.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:43:0)
[11:19:11.602] Timer tick, asking Buildbucket for the build status
[11:19:11.784] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:11.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:44:0) after 1m0s
[11:20:11.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:44:0)
[11:20:11.861] Timer tick, asking Buildbucket for the build status
[11:20:12.033] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:12.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:45:0) after 1m0s
[11:21:12.055] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:45:0)
[11:21:12.055] Timer tick, asking Buildbucket for the build status
[11:21:12.122] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:12.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:46:0) after 1m0s
[11:22:12.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:46:0)
[11:22:12.144] Timer tick, asking Buildbucket for the build status
[11:22:12.251] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:12.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:47:0) after 1m0s
[11:23:12.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:47:0)
[11:23:12.295] Timer tick, asking Buildbucket for the build status
[11:23:12.441] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:12.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:48:0) after 1m0s
[11:24:12.463] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:48:0)
[11:24:12.463] Timer tick, asking Buildbucket for the build status
[11:24:12.540] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:12.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:49:0) after 1m0s
[11:25:12.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:49:0)
[11:25:12.713] Timer tick, asking Buildbucket for the build status
[11:25:12.799] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:12.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:50:0) after 1m0s
[11:26:12.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:50:0)
[11:26:12.846] Timer tick, asking Buildbucket for the build status
[11:26:12.939] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:12.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:51:0) after 1m0s
[11:27:13.058] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:51:0)
[11:27:13.058] Timer tick, asking Buildbucket for the build status
[11:27:13.202] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:13.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:52:0) after 1m0s
[11:28:13.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:52:0)
[11:28:13.381] Timer tick, asking Buildbucket for the build status
[11:28:13.573] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:13.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:53:0) after 1m0s
[11:29:13.711] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:53:0)
[11:29:13.711] Timer tick, asking Buildbucket for the build status
[11:29:13.778] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:13.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:54:0) after 1m0s
[11:30:13.893] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:54:0)
[11:30:13.893] Timer tick, asking Buildbucket for the build status
[11:30:13.970] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:13.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:55:0) after 1m0s
[11:31:14.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:55:0)
[11:31:14.069] Timer tick, asking Buildbucket for the build status
[11:31:14.200] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:14.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:56:0) after 1m0s
[11:32:14.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:56:0)
[11:32:14.217] Timer tick, asking Buildbucket for the build status
[11:32:14.299] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:14.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:57:0) after 1m0s
[11:33:14.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:57:0)
[11:33:14.342] Timer tick, asking Buildbucket for the build status
[11:33:14.525] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:14.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:58:0) after 1m0s
[11:34:14.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:58:0)
[11:34:14.547] Timer tick, asking Buildbucket for the build status
[11:34:17.458] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:17.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:59:0) after 1m0s
[11:35:17.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:59:0)
[11:35:17.477] Timer tick, asking Buildbucket for the build status
[11:35:17.554] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:17.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:60:0) after 1m0s
[11:36:17.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:60:0)
[11:36:17.607] Timer tick, asking Buildbucket for the build status
[11:36:17.685] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:17.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:61:0) after 1m0s
[11:37:17.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:61:0)
[11:37:17.749] Timer tick, asking Buildbucket for the build status
[11:37:17.805] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:17.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:62:0) after 1m0s
[11:38:17.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:62:0)
[11:38:17.889] Timer tick, asking Buildbucket for the build status
[11:38:18.012] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:18.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:63:0) after 1m0s
[11:39:18.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:63:0)
[11:39:18.026] Timer tick, asking Buildbucket for the build status
[11:39:18.106] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:18.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:64:0) after 1m0s
[11:40:18.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:64:0)
[11:40:18.169] Timer tick, asking Buildbucket for the build status
[11:40:19.589] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:19.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:65:0) after 1m0s
[11:41:19.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:65:0)
[11:41:19.640] Timer tick, asking Buildbucket for the build status
[11:41:19.725] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:19.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:66:0) after 1m0s
[11:42:19.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:66:0)
[11:42:19.837] Timer tick, asking Buildbucket for the build status
[11:42:19.902] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:19.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:67:0) after 1m0s
[11:43:19.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:67:0)
[11:43:19.974] Timer tick, asking Buildbucket for the build status
[11:43:20.484] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:20.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:68:0) after 1m0s
[11:44:20.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:68:0)
[11:44:20.569] Timer tick, asking Buildbucket for the build status
[11:44:20.629] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:20.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:69:0) after 1m0s
[11:45:20.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:69:0)
[11:45:20.813] Timer tick, asking Buildbucket for the build status
[11:45:20.911] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:20.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:70:0) after 1m0s
[11:46:20.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:70:0)
[11:46:20.973] Timer tick, asking Buildbucket for the build status
[11:46:21.032] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:21.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:71:0) after 1m0s
[11:47:21.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:71:0)
[11:47:21.117] Timer tick, asking Buildbucket for the build status
[11:47:21.231] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:21.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:72:0) after 1m0s
[11:48:21.294] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:72:0)
[11:48:21.294] Timer tick, asking Buildbucket for the build status
[11:48:21.433] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:21.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:73:0) after 1m0s
[11:49:21.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:73:0)
[11:49:21.459] Timer tick, asking Buildbucket for the build status
[11:49:21.619] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:21.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:74:0) after 1m0s
[11:50:21.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:74:0)
[11:50:21.687] Timer tick, asking Buildbucket for the build status
[11:50:21.869] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:21.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:75:0) after 1m0s
[11:51:21.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:75:0)
[11:51:21.974] Timer tick, asking Buildbucket for the build status
[11:51:22.042] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:22.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:76:0) after 1m0s
[11:52:22.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:76:0)
[11:52:22.060] Timer tick, asking Buildbucket for the build status
[11:52:22.214] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:22.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:77:0) after 1m0s
[11:53:22.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:77:0)
[11:53:22.219] Timer tick, asking Buildbucket for the build status
[11:53:22.362] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:22.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:78:0) after 1m0s
[11:54:22.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:78:0)
[11:54:22.421] Timer tick, asking Buildbucket for the build status
[11:54:22.503] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:22.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:79:0) after 1m0s
[11:55:22.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:79:0)
[11:55:22.532] Timer tick, asking Buildbucket for the build status
[11:55:22.751] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:22.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:80:0) after 1m0s
[11:56:22.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:80:0)
[11:56:22.811] Timer tick, asking Buildbucket for the build status
[11:56:22.875] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:22.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:81:0) after 1m0s
[11:57:22.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:81:0)
[11:57:22.971] Timer tick, asking Buildbucket for the build status
[11:57:23.038] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:23.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:82:0) after 1m0s
[11:58:23.199] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:82:0)
[11:58:23.199] Timer tick, asking Buildbucket for the build status
[11:58:23.329] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:23.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:83:0) after 1m0s
[11:59:23.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:83:0)
[11:59:23.542] Timer tick, asking Buildbucket for the build status
[11:59:23.675] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:23.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:84:0) after 1m0s
[12:00:23.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:84:0)
[12:00:23.851] Timer tick, asking Buildbucket for the build status
[12:00:23.915] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:23.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:85:0) after 1m0s
[12:01:23.986] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:85:0)
[12:01:23.986] Timer tick, asking Buildbucket for the build status
[12:01:24.235] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:24.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:86:0) after 1m0s
[12:02:24.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:86:0)
[12:02:24.304] Timer tick, asking Buildbucket for the build status
[12:02:24.423] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:24.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:87:0) after 1m0s
[12:03:24.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:87:0)
[12:03:24.580] Timer tick, asking Buildbucket for the build status
[12:03:24.745] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:24.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:88:0) after 1m0s
[12:04:24.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:88:0)
[12:04:24.825] Timer tick, asking Buildbucket for the build status
[12:04:24.930] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:24.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:89:0) after 1m0s
[12:05:25.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:89:0)
[12:05:25.066] Timer tick, asking Buildbucket for the build status
[12:05:25.290] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:25.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:90:0) after 1m0s
[12:06:25.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:90:0)
[12:06:25.492] Timer tick, asking Buildbucket for the build status
[12:06:25.612] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:25.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:91:0) after 1m0s
[12:07:25.684] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:91:0)
[12:07:25.684] Timer tick, asking Buildbucket for the build status
[12:07:25.974] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:25.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:92:0) after 1m0s
[12:08:26.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:92:0)
[12:08:26.035] Timer tick, asking Buildbucket for the build status
[12:08:26.178] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:26.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:93:0) after 1m0s
[12:09:26.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:93:0)
[12:09:26.283] Timer tick, asking Buildbucket for the build status
[12:09:26.356] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:26.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:94:0) after 1m0s
[12:10:26.381] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:94:0)
[12:10:26.381] Timer tick, asking Buildbucket for the build status
[12:10:26.455] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:26.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:95:0) after 1m0s
[12:11:26.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:95:0)
[12:11:26.508] Timer tick, asking Buildbucket for the build status
[12:11:26.594] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:26.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:96:0) after 1m0s
[12:12:26.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:96:0)
[12:12:26.655] Timer tick, asking Buildbucket for the build status
[12:12:26.838] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:26.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:97:0) after 1m0s
[12:13:26.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:97:0)
[12:13:26.871] Timer tick, asking Buildbucket for the build status
[12:13:27.169] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:27.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:98:0) after 1m0s
[12:14:27.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:98:0)
[12:14:27.249] Timer tick, asking Buildbucket for the build status
[12:14:27.542] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:27.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:99:0) after 1m0s
[12:15:27.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:99:0)
[12:15:27.570] Timer tick, asking Buildbucket for the build status
[12:15:27.641] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:27.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:100:0) after 1m0s
[12:16:27.668] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:100:0)
[12:16:27.668] Timer tick, asking Buildbucket for the build status
[12:16:27.782] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:27.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:101:0) after 1m0s
[12:17:27.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:101:0)
[12:17:27.903] Timer tick, asking Buildbucket for the build status
[12:17:27.964] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:27.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:102:0) after 1m0s
[12:18:28.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:102:0)
[12:18:28.183] Timer tick, asking Buildbucket for the build status
[12:18:28.534] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:28.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:103:0) after 1m0s
[12:19:28.580] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:103:0)
[12:19:28.580] Timer tick, asking Buildbucket for the build status
[12:19:28.886] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:28.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:104:0) after 1m0s
[12:20:29.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:104:0)
[12:20:29.013] Timer tick, asking Buildbucket for the build status
[12:20:29.187] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:29.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:105:0) after 1m0s
[12:21:29.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:105:0)
[12:21:29.209] Timer tick, asking Buildbucket for the build status
[12:21:29.280] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:29.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:106:0) after 1m0s
[12:22:29.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:106:0)
[12:22:29.345] Timer tick, asking Buildbucket for the build status
[12:22:29.599] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:29.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:107:0) after 1m0s
[12:23:29.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:107:0)
[12:23:29.677] Timer tick, asking Buildbucket for the build status
[12:23:30.122] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:30.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:108:0) after 1m0s
[12:24:30.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:108:0)
[12:24:30.144] Timer tick, asking Buildbucket for the build status
[12:24:30.250] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:30.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:109:0) after 1m0s
[12:25:30.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:109:0)
[12:25:30.282] Timer tick, asking Buildbucket for the build status
[12:25:30.373] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:30.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:110:0) after 1m0s
[12:26:30.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:110:0)
[12:26:30.418] Timer tick, asking Buildbucket for the build status
[12:26:30.630] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:30.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:111:0) after 1m0s
[12:27:30.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:111:0)
[12:27:30.651] Timer tick, asking Buildbucket for the build status
[12:27:30.849] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:30.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:112:0) after 1m0s
[12:28:30.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:112:0)
[12:28:30.907] Timer tick, asking Buildbucket for the build status
[12:28:31.292] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:31.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:113:0) after 1m0s
[12:29:31.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:113:0)
[12:29:31.347] Timer tick, asking Buildbucket for the build status
[12:29:33.069] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:33.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:114:0) after 1m0s
[12:30:33.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:114:0)
[12:30:33.171] Timer tick, asking Buildbucket for the build status
[12:30:33.269] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:33.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:115:0) after 1m0s
[12:31:33.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:115:0)
[12:31:33.258] Timer tick, asking Buildbucket for the build status
[12:31:33.441] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:33.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:116:0) after 1m0s
[12:32:33.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:116:0)
[12:32:33.501] Timer tick, asking Buildbucket for the build status
[12:32:33.773] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:33.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:117:0) after 1m0s
[12:33:33.862] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:117:0)
[12:33:33.862] Timer tick, asking Buildbucket for the build status
[12:33:33.970] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:33.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:118:0) after 1m0s
[12:34:34.009] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:118:0)
[12:34:34.009] Timer tick, asking Buildbucket for the build status
[12:34:34.085] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:34.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:119:0) after 1m0s
[12:35:34.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:119:0)
[12:35:34.116] Timer tick, asking Buildbucket for the build status
[12:35:34.182] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:34.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:120:0) after 1m0s
[12:36:34.240] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:120:0)
[12:36:34.240] Timer tick, asking Buildbucket for the build status
[12:36:34.348] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:34.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:121:0) after 1m0s
[12:37:34.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:121:0)
[12:37:34.391] Timer tick, asking Buildbucket for the build status
[12:37:34.554] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:34.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:122:0) after 1m0s
[12:38:34.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:122:0)
[12:38:34.575] Timer tick, asking Buildbucket for the build status
[12:38:34.759] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:34.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:123:0) after 1m0s
[12:39:34.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:123:0)
[12:39:34.783] Timer tick, asking Buildbucket for the build status
[12:39:34.858] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:34.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:124:0) after 1m0s
[12:40:34.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:124:0)
[12:40:34.895] Timer tick, asking Buildbucket for the build status
[12:40:34.957] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:34.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:125:0) after 1m0s
[12:41:34.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:125:0)
[12:41:34.997] Timer tick, asking Buildbucket for the build status
[12:41:35.130] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:35.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:126:0) after 1m0s
[12:42:35.163] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:126:0)
[12:42:35.163] Timer tick, asking Buildbucket for the build status
[12:42:35.365] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:35.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:127:0) after 1m0s
[12:43:35.391] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:127:0)
[12:43:35.391] Timer tick, asking Buildbucket for the build status
[12:43:35.625] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:35.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:128:0) after 1m0s
[12:44:35.649] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:128:0)
[12:44:35.649] Timer tick, asking Buildbucket for the build status
[12:44:35.909] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:35.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:129:0) after 1m0s
[12:45:35.995] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:129:0)
[12:45:35.995] Timer tick, asking Buildbucket for the build status
[12:45:36.057] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:36.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:130:0) after 1m0s
[12:46:36.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:130:0)
[12:46:36.174] Timer tick, asking Buildbucket for the build status
[12:46:36.387] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:36.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:131:0) after 1m0s
[12:47:36.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:131:0)
[12:47:36.448] Timer tick, asking Buildbucket for the build status
[12:47:36.551] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:36.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:132:0) after 1m0s
[12:48:36.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:132:0)
[12:48:36.612] Timer tick, asking Buildbucket for the build status
[12:48:36.727] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:36.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:133:0) after 1m0s
[12:49:36.757] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:133:0)
[12:49:36.757] Timer tick, asking Buildbucket for the build status
[12:49:36.822] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:36.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:134:0) after 1m0s
[12:50:36.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:134:0)
[12:50:36.848] Timer tick, asking Buildbucket for the build status
[12:50:37.108] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:37.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:135:0) after 1m0s
[12:51:37.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:135:0)
[12:51:37.107] Timer tick, asking Buildbucket for the build status
[12:51:37.304] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:37.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:136:0) after 1m0s
[12:52:37.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:136:0)
[12:52:37.328] Timer tick, asking Buildbucket for the build status
[12:52:37.560] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:37.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:137:0) after 1m0s
[12:53:37.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:137:0)
[12:53:37.629] Timer tick, asking Buildbucket for the build status
[12:53:37.770] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:37.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:138:0) after 1m0s
[12:54:37.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:138:0)
[12:54:37.827] Timer tick, asking Buildbucket for the build status
[12:54:37.897] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:37.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:139:0) after 1m0s
[12:55:37.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:139:0)
[12:55:37.917] Timer tick, asking Buildbucket for the build status
[12:55:37.977] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:37.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:140:0) after 1m0s
[12:56:38.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:140:0)
[12:56:38.040] Timer tick, asking Buildbucket for the build status
[12:56:38.334] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:38.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:141:0) after 1m0s
[12:57:38.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:141:0)
[12:57:38.410] Timer tick, asking Buildbucket for the build status
[12:57:38.685] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:38.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:142:0) after 1m0s
[12:58:38.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:142:0)
[12:58:38.713] Timer tick, asking Buildbucket for the build status
[12:58:39.146] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:39.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:143:0) after 1m0s
[12:59:39.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:143:0)
[12:59:39.227] Timer tick, asking Buildbucket for the build status
[12:59:39.287] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:39.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:144:0) after 1m0s
[13:00:39.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:144:0)
[13:00:39.402] Timer tick, asking Buildbucket for the build status
[13:00:39.594] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:39.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:145:0) after 1m0s
[13:01:39.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:145:0)
[13:01:39.666] Timer tick, asking Buildbucket for the build status
[13:01:39.725] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:39.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:146:0) after 1m0s
[13:02:39.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:146:0)
[13:02:39.774] Timer tick, asking Buildbucket for the build status
[13:02:39.862] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:39.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:147:0) after 1m0s
[13:03:39.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:147:0)
[13:03:39.910] Timer tick, asking Buildbucket for the build status
[13:03:40.054] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:40.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:148:0) after 1m0s
[13:04:40.121] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:148:0)
[13:04:40.121] Timer tick, asking Buildbucket for the build status
[13:04:40.202] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:40.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:149:0) after 1m0s
[13:05:40.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:149:0)
[13:05:40.269] Timer tick, asking Buildbucket for the build status
[13:05:40.508] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:40.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:150:0) after 1m0s
[13:06:40.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:150:0)
[13:06:40.640] Timer tick, asking Buildbucket for the build status
[13:06:40.852] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:40.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:151:0) after 1m0s
[13:07:40.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:151:0)
[13:07:40.885] Timer tick, asking Buildbucket for the build status
[13:07:40.958] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:40.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:152:0) after 1m0s
[13:08:41.011] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:152:0)
[13:08:41.011] Timer tick, asking Buildbucket for the build status
[13:08:41.143] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:41.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:153:0) after 1m0s
[13:09:41.220] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:153:0)
[13:09:41.220] Timer tick, asking Buildbucket for the build status
[13:09:41.521] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:41.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:154:0) after 1m0s
[13:10:41.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:154:0)
[13:10:41.589] Timer tick, asking Buildbucket for the build status
[13:10:41.810] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:41.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:155:0) after 1m0s
[13:11:41.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:155:0)
[13:11:41.868] Timer tick, asking Buildbucket for the build status
[13:11:41.953] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:41.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:156:0) after 1m0s
[13:12:41.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:156:0)
[13:12:41.966] Timer tick, asking Buildbucket for the build status
[13:12:42.042] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:42.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:157:0) after 1m0s
[13:13:42.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:157:0)
[13:13:42.068] Timer tick, asking Buildbucket for the build status
[13:13:42.213] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:42.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:158:0) after 1m0s
[13:14:42.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:158:0)
[13:14:42.215] Timer tick, asking Buildbucket for the build status
[13:14:42.283] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:42.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:159:0) after 1m0s
[13:15:42.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:159:0)
[13:15:42.345] Timer tick, asking Buildbucket for the build status
[13:15:42.888] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:42.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:160:0) after 1m0s
[13:16:42.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:160:0)
[13:16:42.900] Timer tick, asking Buildbucket for the build status
[13:16:45.291] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:45.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:161:0) after 1m0s
[13:17:45.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:161:0)
[13:17:45.366] Timer tick, asking Buildbucket for the build status
[13:17:46.897] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:46.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:162:0) after 1m0s
[13:18:46.979] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:162:0)
[13:18:46.979] Timer tick, asking Buildbucket for the build status
[13:18:47.132] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:47.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:163:0) after 1m0s
[13:19:47.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:163:0)
[13:19:47.244] Timer tick, asking Buildbucket for the build status
[13:19:47.332] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:47.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:164:0) after 1m0s
[13:20:47.495] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:164:0)
[13:20:47.495] Timer tick, asking Buildbucket for the build status
[13:20:47.560] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:47.560] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:165:0) after 1m0s
[13:21:47.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:165:0)
[13:21:47.736] Timer tick, asking Buildbucket for the build status
[13:21:47.855] Build 8870819104834029424: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:47.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9036249166762380576:166:0) after 1m0s
[13:22:03.381] Received PubSub notification, asking Buildbucket for the build status
[13:22:03.588] Build 8870819104834029424: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:22:03.588] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1598534522569741",
"created_by": "project:chromium",
"created_ts": "1598524676441811",
"id": "8870819104834029424",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"883df4a2cb671f49635fe6e3d149e02feb4ec3f0\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-6-86zm\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 435, \"got_angle_revision\": \"97013e85adfceabb4e4c4d95178e186481b82362\", \"got_dawn_revision\": \"8c012e879646b65924817b8dfb75c7073a0b1b02\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"883df4a2cb671f49635fe6e3d149e02feb4ec3f0\", \"got_revision_cp\": \"refs/heads/master@{#802172}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"1f48854dae26a2d129ae771e671a2172acb2da86\", \"got_v8_revision\": \"2a00f38a34d5c3dffd2ef5cd1f538534ce7d66af\", \"got_v8_revision_cp\": \"refs/heads/8.7.26@{#1}\", \"got_webrtc_revision\": \"c75c4280763435eeb44785dfc76f098bf06743bb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31999}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"883df4a2cb671f49635fe6e3d149e02feb4ec3f0\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-6-86zm\"], \"image\": [\"chrome-win10-1703-20081700-d9392568d25\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5498-3454aa6\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1598524717563685",
"status": "COMPLETED",
"status_changed_ts": "1598534522855578",
"tags": [
"build_address:luci.chromium.ci/win-official/435",
"builder:win-official",
"buildset:commit/git/883df4a2cb671f49635fe6e3d149e02feb4ec3f0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/883df4a2cb671f49635fe6e3d149e02feb4ec3f0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9036249166762380576",
"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/8870819104834029424/+/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:4e485065a910d910",
"user_agent:luci-scheduler"
],
"updated_ts": "1598534522860341",
"url": "https://ci.chromium.org/b/8870819104834029424",
"utcnow_ts": "1598534523539104"
}
}
[13:22:03.588] Invocation finished in 2h44m8.369674595s with status SUCCEEDED