[07:51:19.093] New invocation is queued and will start shortly
[07:51:20.266] Starting the invocation (attempt 1)
[07:51:20.315] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:51:20.315] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:51:20.315] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021764133852339456",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021764133852339456",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"gitiles_ref:refs/heads/master"
]
}
[07:51:21.557] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612338680495573",
"id": "8856334071719880784",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612338681389330",
"tags": [
"build_address:luci.chromium.ci/win-official/1525",
"builder:win-official",
"buildset:commit/git/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021764133852339456",
"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/8856334071719880784/+/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": "1612338681389474",
"url": "https://ci.chromium.org/b/8856334071719880784",
"utcnow_ts": "1612338681549224"
}
}
[07:51:21.557] Task URL: https://ci.chromium.org/b/8856334071719880784
[07:51:21.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:2:0) after 1m0s
[07:52:05.552] Received PubSub notification, asking Buildbucket for the build status
[07:52:05.615] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:21.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:2:0)
[07:52:21.748] Timer tick, asking Buildbucket for the build status
[07:52:22.005] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:22.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:4:0) after 1m0s
[07:53:22.080] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:4:0)
[07:53:22.080] Timer tick, asking Buildbucket for the build status
[07:53:22.165] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:22.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:5:0) after 1m0s
[07:54:22.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:5:0)
[07:54:22.193] Timer tick, asking Buildbucket for the build status
[07:54:22.273] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:22.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:6:0) after 1m0s
[07:55:23.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:6:0)
[07:55:23.211] Timer tick, asking Buildbucket for the build status
[07:55:23.353] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:23.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:7:0) after 1m0s
[07:56:23.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:7:0)
[07:56:23.464] Timer tick, asking Buildbucket for the build status
[07:56:23.567] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:23.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:8:0) after 1m0s
[07:57:23.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:8:0)
[07:57:23.660] Timer tick, asking Buildbucket for the build status
[07:57:23.749] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:23.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:9:0) after 1m0s
[07:58:23.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:9:0)
[07:58:23.842] Timer tick, asking Buildbucket for the build status
[07:58:24.552] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:24.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:10:0) after 1m0s
[07:59:24.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:10:0)
[07:59:24.772] Timer tick, asking Buildbucket for the build status
[07:59:26.574] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:26.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:11:0) after 1m0s
[08:00:26.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:11:0)
[08:00:26.692] Timer tick, asking Buildbucket for the build status
[08:00:27.268] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:27.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:12:0) after 1m0s
[08:01:27.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:12:0)
[08:01:27.345] Timer tick, asking Buildbucket for the build status
[08:01:27.593] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:27.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:13:0) after 1m0s
[08:02:27.606] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:13:0)
[08:02:27.606] Timer tick, asking Buildbucket for the build status
[08:02:27.801] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:27.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:14:0) after 1m0s
[08:03:27.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:14:0)
[08:03:27.783] Timer tick, asking Buildbucket for the build status
[08:03:28.140] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:28.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:15:0) after 1m0s
[08:04:28.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:15:0)
[08:04:28.302] Timer tick, asking Buildbucket for the build status
[08:04:28.421] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:28.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:16:0) after 1m0s
[08:05:29.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:16:0)
[08:05:29.189] Timer tick, asking Buildbucket for the build status
[08:05:29.257] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:29.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:17:0) after 1m0s
[08:06:29.478] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:17:0)
[08:06:29.478] Timer tick, asking Buildbucket for the build status
[08:06:29.727] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:29.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:18:0) after 1m0s
[08:07:29.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:18:0)
[08:07:29.777] Timer tick, asking Buildbucket for the build status
[08:07:29.842] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:29.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:19:0) after 1m0s
[08:08:29.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:19:0)
[08:08:29.899] Timer tick, asking Buildbucket for the build status
[08:08:29.965] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:29.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:20:0) after 1m0s
[08:09:30.040] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:20:0)
[08:09:30.040] Timer tick, asking Buildbucket for the build status
[08:09:30.102] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:30.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:21:0) after 1m0s
[08:10:30.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:21:0)
[08:10:30.149] Timer tick, asking Buildbucket for the build status
[08:10:30.320] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:30.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:22:0) after 1m0s
[08:11:30.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:22:0)
[08:11:30.415] Timer tick, asking Buildbucket for the build status
[08:11:30.546] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:30.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:23:0) after 1m0s
[08:12:30.582] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:23:0)
[08:12:30.582] Timer tick, asking Buildbucket for the build status
[08:12:30.746] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:30.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:24:0) after 1m0s
[08:13:30.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:24:0)
[08:13:30.825] Timer tick, asking Buildbucket for the build status
[08:13:31.004] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:31.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:25:0) after 1m0s
[08:14:31.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:25:0)
[08:14:31.076] Timer tick, asking Buildbucket for the build status
[08:14:31.408] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:31.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:26:0) after 1m0s
[08:15:31.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:26:0)
[08:15:31.471] Timer tick, asking Buildbucket for the build status
[08:15:31.556] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:31.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:27:0) after 1m0s
[08:16:31.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:27:0)
[08:16:31.575] Timer tick, asking Buildbucket for the build status
[08:16:31.671] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:31.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:28:0) after 1m0s
[08:17:31.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:28:0)
[08:17:31.747] Timer tick, asking Buildbucket for the build status
[08:17:32.035] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:32.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:29:0) after 1m0s
[08:18:32.145] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:29:0)
[08:18:32.145] Timer tick, asking Buildbucket for the build status
[08:18:32.210] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:32.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:30:0) after 1m0s
[08:19:32.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:30:0)
[08:19:32.364] Timer tick, asking Buildbucket for the build status
[08:19:32.421] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:32.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:31:0) after 1m0s
[08:20:32.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:31:0)
[08:20:32.537] Timer tick, asking Buildbucket for the build status
[08:20:32.607] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:32.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:32:0) after 1m0s
[08:21:32.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:32:0)
[08:21:32.773] Timer tick, asking Buildbucket for the build status
[08:21:32.875] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:32.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:33:0) after 1m0s
[08:22:32.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:33:0)
[08:22:32.951] Timer tick, asking Buildbucket for the build status
[08:22:33.031] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:33.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:34:0) after 1m0s
[08:23:33.039] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:34:0)
[08:23:33.039] Timer tick, asking Buildbucket for the build status
[08:23:33.222] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:33.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:35:0) after 1m0s
[08:24:33.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:35:0)
[08:24:33.230] Timer tick, asking Buildbucket for the build status
[08:24:33.332] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:33.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:36:0) after 1m0s
[08:25:33.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:36:0)
[08:25:33.414] Timer tick, asking Buildbucket for the build status
[08:25:33.509] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:33.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:37:0) after 1m0s
[08:26:33.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:37:0)
[08:26:33.553] Timer tick, asking Buildbucket for the build status
[08:26:33.674] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:33.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:38:0) after 1m0s
[08:27:33.722] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:38:0)
[08:27:33.722] Timer tick, asking Buildbucket for the build status
[08:27:33.807] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:33.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:39:0) after 1m0s
[08:28:33.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:39:0)
[08:28:33.828] Timer tick, asking Buildbucket for the build status
[08:28:33.951] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:33.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:40:0) after 1m0s
[08:29:34.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:40:0)
[08:29:34.006] Timer tick, asking Buildbucket for the build status
[08:29:34.531] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:34.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:41:0) after 1m0s
[08:30:34.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:41:0)
[08:30:34.562] Timer tick, asking Buildbucket for the build status
[08:30:34.630] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:34.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:42:0) after 1m0s
[08:31:34.610] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:42:0)
[08:31:34.610] Timer tick, asking Buildbucket for the build status
[08:31:34.894] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:34.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:43:0) after 1m0s
[08:32:34.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:43:0)
[08:32:34.912] Timer tick, asking Buildbucket for the build status
[08:32:34.970] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:34.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:44:0) after 1m0s
[08:33:35.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:44:0)
[08:33:35.047] Timer tick, asking Buildbucket for the build status
[08:33:35.097] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:35.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:45:0) after 1m0s
[08:34:35.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:45:0)
[08:34:35.113] Timer tick, asking Buildbucket for the build status
[08:34:35.571] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:35.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:46:0) after 1m0s
[08:35:35.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:46:0)
[08:35:35.601] Timer tick, asking Buildbucket for the build status
[08:35:35.787] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:35.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:47:0) after 1m0s
[08:36:35.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:47:0)
[08:36:35.836] Timer tick, asking Buildbucket for the build status
[08:36:35.975] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:35.978] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:48:0) after 1m0s
[08:37:36.025] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:48:0)
[08:37:36.025] Timer tick, asking Buildbucket for the build status
[08:37:36.082] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:36.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:49:0) after 1m0s
[08:38:36.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:49:0)
[08:38:36.132] Timer tick, asking Buildbucket for the build status
[08:38:36.221] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:36.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:50:0) after 1m0s
[08:39:36.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:50:0)
[08:39:36.271] Timer tick, asking Buildbucket for the build status
[08:39:36.324] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:36.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:51:0) after 1m0s
[08:40:36.404] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:51:0)
[08:40:36.404] Timer tick, asking Buildbucket for the build status
[08:40:36.476] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:36.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:52:0) after 1m0s
[08:41:36.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:52:0)
[08:41:36.535] Timer tick, asking Buildbucket for the build status
[08:41:36.638] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:36.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:53:0) after 1m0s
[08:42:36.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:53:0)
[08:42:36.662] Timer tick, asking Buildbucket for the build status
[08:42:36.724] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:36.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:54:0) after 1m0s
[08:43:36.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:54:0)
[08:43:36.836] Timer tick, asking Buildbucket for the build status
[08:43:37.027] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:37.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:55:0) after 1m0s
[08:44:37.069] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:55:0)
[08:44:37.069] Timer tick, asking Buildbucket for the build status
[08:44:37.140] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:37.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:56:0) after 1m0s
[08:45:37.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:56:0)
[08:45:37.214] Timer tick, asking Buildbucket for the build status
[08:45:37.609] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:37.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:57:0) after 1m0s
[08:46:37.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:57:0)
[08:46:37.691] Timer tick, asking Buildbucket for the build status
[08:46:38.100] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:38.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:58:0) after 1m0s
[08:47:38.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:58:0)
[08:47:38.303] Timer tick, asking Buildbucket for the build status
[08:47:38.378] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:38.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:59:0) after 1m0s
[08:48:38.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:59:0)
[08:48:38.441] Timer tick, asking Buildbucket for the build status
[08:48:38.520] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:38.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:60:0) after 1m0s
[08:49:38.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:60:0)
[08:49:38.605] Timer tick, asking Buildbucket for the build status
[08:49:38.704] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:38.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:61:0) after 1m0s
[08:50:38.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:61:0)
[08:50:38.772] Timer tick, asking Buildbucket for the build status
[08:50:38.911] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:38.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:62:0) after 1m0s
[08:51:38.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:62:0)
[08:51:38.974] Timer tick, asking Buildbucket for the build status
[08:51:39.239] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:39.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:63:0) after 1m0s
[08:52:39.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:63:0)
[08:52:39.273] Timer tick, asking Buildbucket for the build status
[08:52:39.342] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:39.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:64:0) after 1m0s
[08:53:39.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:64:0)
[08:53:39.416] Timer tick, asking Buildbucket for the build status
[08:53:39.653] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:39.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:65:0) after 1m0s
[08:54:39.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:65:0)
[08:54:39.764] Timer tick, asking Buildbucket for the build status
[08:54:39.831] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:39.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:66:0) after 1m0s
[08:55:39.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:66:0)
[08:55:39.955] Timer tick, asking Buildbucket for the build status
[08:55:40.011] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:40.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:67:0) after 1m0s
[08:56:40.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:67:0)
[08:56:40.081] Timer tick, asking Buildbucket for the build status
[08:56:40.288] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:40.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:68:0) after 1m0s
[08:57:40.323] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:68:0)
[08:57:40.323] Timer tick, asking Buildbucket for the build status
[08:57:40.391] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:40.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:69:0) after 1m0s
[08:58:40.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:69:0)
[08:58:40.439] Timer tick, asking Buildbucket for the build status
[08:58:40.518] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:40.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:70:0) after 1m0s
[08:59:40.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:70:0)
[08:59:40.531] Timer tick, asking Buildbucket for the build status
[08:59:40.595] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:40.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:71:0) after 1m0s
[09:00:40.594] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:71:0)
[09:00:40.594] Timer tick, asking Buildbucket for the build status
[09:00:40.740] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:40.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:72:0) after 1m0s
[09:01:40.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:72:0)
[09:01:40.764] Timer tick, asking Buildbucket for the build status
[09:01:40.878] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:40.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:73:0) after 1m0s
[09:02:40.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:73:0)
[09:02:40.874] Timer tick, asking Buildbucket for the build status
[09:02:40.931] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:40.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:74:0) after 1m0s
[09:03:41.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:74:0)
[09:03:41.029] Timer tick, asking Buildbucket for the build status
[09:03:41.110] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:41.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:75:0) after 1m0s
[09:04:41.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:75:0)
[09:04:41.218] Timer tick, asking Buildbucket for the build status
[09:04:41.414] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:41.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:76:0) after 1m0s
[09:05:41.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:76:0)
[09:05:41.625] Timer tick, asking Buildbucket for the build status
[09:05:42.046] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:42.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:77:0) after 1m0s
[09:06:42.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:77:0)
[09:06:42.093] Timer tick, asking Buildbucket for the build status
[09:06:42.280] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:42.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:78:0) after 1m0s
[09:07:42.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:78:0)
[09:07:42.442] Timer tick, asking Buildbucket for the build status
[09:07:42.542] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:42.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:79:0) after 1m0s
[09:08:42.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:79:0)
[09:08:42.545] Timer tick, asking Buildbucket for the build status
[09:08:42.811] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:42.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:80:0) after 1m0s
[09:09:42.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:80:0)
[09:09:42.863] Timer tick, asking Buildbucket for the build status
[09:09:43.214] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:43.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:81:0) after 1m0s
[09:10:43.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:81:0)
[09:10:43.282] Timer tick, asking Buildbucket for the build status
[09:10:43.348] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:43.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:82:0) after 1m0s
[09:11:43.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:82:0)
[09:11:43.482] Timer tick, asking Buildbucket for the build status
[09:11:43.602] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:43.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:83:0) after 1m0s
[09:12:43.709] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:83:0)
[09:12:43.709] Timer tick, asking Buildbucket for the build status
[09:12:43.908] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:43.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:84:0) after 1m0s
[09:13:43.984] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:84:0)
[09:13:43.984] Timer tick, asking Buildbucket for the build status
[09:13:44.051] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:44.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:85:0) after 1m0s
[09:14:44.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:85:0)
[09:14:44.112] Timer tick, asking Buildbucket for the build status
[09:14:44.177] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:44.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:86:0) after 1m0s
[09:15:44.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:86:0)
[09:15:44.314] Timer tick, asking Buildbucket for the build status
[09:15:44.385] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:44.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:87:0) after 1m0s
[09:16:44.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:87:0)
[09:16:44.523] Timer tick, asking Buildbucket for the build status
[09:16:44.921] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:44.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:88:0) after 1m0s
[09:17:44.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:88:0)
[09:17:44.971] Timer tick, asking Buildbucket for the build status
[09:17:45.120] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:45.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:89:0) after 1m0s
[09:18:45.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:89:0)
[09:18:45.244] Timer tick, asking Buildbucket for the build status
[09:18:45.431] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:45.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:90:0) after 1m0s
[09:19:45.559] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:90:0)
[09:19:45.559] Timer tick, asking Buildbucket for the build status
[09:19:45.644] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:45.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:91:0) after 1m0s
[09:20:45.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:91:0)
[09:20:45.650] Timer tick, asking Buildbucket for the build status
[09:20:45.702] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:45.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:92:0) after 1m0s
[09:21:45.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:92:0)
[09:21:45.727] Timer tick, asking Buildbucket for the build status
[09:21:45.818] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:45.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:93:0) after 1m0s
[09:22:45.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:93:0)
[09:22:45.894] Timer tick, asking Buildbucket for the build status
[09:22:45.946] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:45.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:94:0) after 1m0s
[09:23:46.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:94:0)
[09:23:46.008] Timer tick, asking Buildbucket for the build status
[09:23:46.067] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:46.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:95:0) after 1m0s
[09:24:46.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:95:0)
[09:24:46.177] Timer tick, asking Buildbucket for the build status
[09:24:46.236] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:46.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:96:0) after 1m0s
[09:25:46.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:96:0)
[09:25:46.262] Timer tick, asking Buildbucket for the build status
[09:25:46.322] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:46.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:97:0) after 1m0s
[09:26:46.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:97:0)
[09:26:46.469] Timer tick, asking Buildbucket for the build status
[09:26:46.575] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:46.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:98:0) after 1m0s
[09:27:46.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:98:0)
[09:27:46.669] Timer tick, asking Buildbucket for the build status
[09:27:46.802] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:46.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:99:0) after 1m0s
[09:28:47.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:99:0)
[09:28:47.691] Timer tick, asking Buildbucket for the build status
[09:28:47.758] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:47.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:100:0) after 1m0s
[09:29:47.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:100:0)
[09:29:47.765] Timer tick, asking Buildbucket for the build status
[09:29:47.855] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:47.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:101:0) after 1m0s
[09:30:47.887] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:101:0)
[09:30:47.887] Timer tick, asking Buildbucket for the build status
[09:30:47.960] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:47.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:102:0) after 1m0s
[09:31:47.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:102:0)
[09:31:47.939] Timer tick, asking Buildbucket for the build status
[09:31:48.047] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:48.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:103:0) after 1m0s
[09:32:48.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:103:0)
[09:32:48.077] Timer tick, asking Buildbucket for the build status
[09:32:48.142] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:48.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:104:0) after 1m0s
[09:33:48.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:104:0)
[09:33:48.191] Timer tick, asking Buildbucket for the build status
[09:33:48.326] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:48.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:105:0) after 1m0s
[09:34:48.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:105:0)
[09:34:48.362] Timer tick, asking Buildbucket for the build status
[09:34:48.436] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:48.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:106:0) after 1m0s
[09:35:48.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:106:0)
[09:35:48.514] Timer tick, asking Buildbucket for the build status
[09:35:48.570] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:48.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:107:0) after 1m0s
[09:36:49.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:107:0)
[09:36:49.817] Timer tick, asking Buildbucket for the build status
[09:36:50.929] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:50.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:108:0) after 1m0s
[09:37:51.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:108:0)
[09:37:51.237] Timer tick, asking Buildbucket for the build status
[09:37:58.898] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:58.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:109:0) after 1m0s
[09:38:58.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:109:0)
[09:38:58.890] Timer tick, asking Buildbucket for the build status
[09:38:58.940] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:58.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:110:0) after 1m0s
[09:39:58.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:110:0)
[09:39:58.965] Timer tick, asking Buildbucket for the build status
[09:39:59.069] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:59.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:111:0) after 1m0s
[09:40:59.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:111:0)
[09:40:59.117] Timer tick, asking Buildbucket for the build status
[09:40:59.186] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:59.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:112:0) after 1m0s
[09:41:59.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:112:0)
[09:41:59.237] Timer tick, asking Buildbucket for the build status
[09:41:59.345] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:59.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:113:0) after 1m0s
[09:42:59.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:113:0)
[09:42:59.356] Timer tick, asking Buildbucket for the build status
[09:42:59.424] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:59.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:114:0) after 1m0s
[09:43:59.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:114:0)
[09:43:59.535] Timer tick, asking Buildbucket for the build status
[09:43:59.632] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:59.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:115:0) after 1m0s
[09:44:59.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:115:0)
[09:44:59.652] Timer tick, asking Buildbucket for the build status
[09:45:01.094] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:01.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:116:0) after 1m0s
[09:46:01.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:116:0)
[09:46:01.563] Timer tick, asking Buildbucket for the build status
[09:46:01.684] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:01.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:117:0) after 1m0s
[09:47:01.806] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:117:0)
[09:47:01.806] Timer tick, asking Buildbucket for the build status
[09:47:02.113] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:02.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:118:0) after 1m0s
[09:48:02.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:118:0)
[09:48:02.131] Timer tick, asking Buildbucket for the build status
[09:48:02.192] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:02.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:119:0) after 1m0s
[09:49:02.219] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:119:0)
[09:49:02.219] Timer tick, asking Buildbucket for the build status
[09:49:02.280] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:02.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:120:0) after 1m0s
[09:50:02.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:120:0)
[09:50:02.288] Timer tick, asking Buildbucket for the build status
[09:50:02.349] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:02.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:121:0) after 1m0s
[09:51:02.360] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:121:0)
[09:51:02.360] Timer tick, asking Buildbucket for the build status
[09:51:02.442] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:02.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:122:0) after 1m0s
[09:52:02.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:122:0)
[09:52:02.474] Timer tick, asking Buildbucket for the build status
[09:52:03.393] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:03.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:123:0) after 1m0s
[09:53:03.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:123:0)
[09:53:03.440] Timer tick, asking Buildbucket for the build status
[09:53:03.506] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:03.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:124:0) after 1m0s
[09:54:03.530] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:124:0)
[09:54:03.530] Timer tick, asking Buildbucket for the build status
[09:54:03.633] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:03.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:125:0) after 1m0s
[09:55:03.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:125:0)
[09:55:03.657] Timer tick, asking Buildbucket for the build status
[09:55:04.185] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:04.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:126:0) after 1m0s
[09:56:04.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:126:0)
[09:56:04.314] Timer tick, asking Buildbucket for the build status
[09:56:04.856] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:04.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:127:0) after 1m0s
[09:57:04.929] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:127:0)
[09:57:04.929] Timer tick, asking Buildbucket for the build status
[09:57:05.296] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:05.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:128:0) after 1m0s
[09:58:05.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:128:0)
[09:58:05.281] Timer tick, asking Buildbucket for the build status
[09:58:05.352] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:05.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:129:0) after 1m0s
[09:59:05.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:129:0)
[09:59:05.407] Timer tick, asking Buildbucket for the build status
[09:59:05.654] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:05.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:130:0) after 1m0s
[10:00:05.792] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:130:0)
[10:00:05.792] Timer tick, asking Buildbucket for the build status
[10:00:05.977] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:05.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:131:0) after 1m0s
[10:01:06.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:131:0)
[10:01:06.602] Timer tick, asking Buildbucket for the build status
[10:01:06.667] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:06.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:132:0) after 1m0s
[10:02:06.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:132:0)
[10:02:06.826] Timer tick, asking Buildbucket for the build status
[10:02:06.979] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:06.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:133:0) after 1m0s
[10:03:06.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:133:0)
[10:03:06.966] Timer tick, asking Buildbucket for the build status
[10:03:07.028] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:07.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:134:0) after 1m0s
[10:04:07.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:134:0)
[10:04:07.106] Timer tick, asking Buildbucket for the build status
[10:04:07.162] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:07.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:135:0) after 1m0s
[10:05:07.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:135:0)
[10:05:07.228] Timer tick, asking Buildbucket for the build status
[10:05:07.430] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:07.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:136:0) after 1m0s
[10:06:07.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:136:0)
[10:06:07.482] Timer tick, asking Buildbucket for the build status
[10:06:07.679] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:07.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:137:0) after 1m0s
[10:07:07.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:137:0)
[10:07:07.689] Timer tick, asking Buildbucket for the build status
[10:07:07.922] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:07.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:138:0) after 1m0s
[10:08:07.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:138:0)
[10:08:07.947] Timer tick, asking Buildbucket for the build status
[10:08:08.149] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:08.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:139:0) after 1m0s
[10:09:08.152] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:139:0)
[10:09:08.152] Timer tick, asking Buildbucket for the build status
[10:09:08.423] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:08.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:140:0) after 1m0s
[10:10:08.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:140:0)
[10:10:08.499] Timer tick, asking Buildbucket for the build status
[10:10:08.787] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:08.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:141:0) after 1m0s
[10:11:08.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:141:0)
[10:11:08.812] Timer tick, asking Buildbucket for the build status
[10:11:08.914] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:08.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:142:0) after 1m0s
[10:12:08.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:142:0)
[10:12:08.939] Timer tick, asking Buildbucket for the build status
[10:12:09.062] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:09.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:143:0) after 1m0s
[10:13:09.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:143:0)
[10:13:09.118] Timer tick, asking Buildbucket for the build status
[10:13:09.535] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:09.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:144:0) after 1m0s
[10:14:09.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:144:0)
[10:14:09.556] Timer tick, asking Buildbucket for the build status
[10:14:09.642] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:09.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:145:0) after 1m0s
[10:15:09.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:145:0)
[10:15:09.666] Timer tick, asking Buildbucket for the build status
[10:15:09.717] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:09.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:146:0) after 1m0s
[10:16:09.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:146:0)
[10:16:09.741] Timer tick, asking Buildbucket for the build status
[10:16:09.959] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:09.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:147:0) after 1m0s
[10:17:10.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:147:0)
[10:17:10.002] Timer tick, asking Buildbucket for the build status
[10:17:10.159] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:10.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:148:0) after 1m0s
[10:18:10.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:148:0)
[10:18:10.248] Timer tick, asking Buildbucket for the build status
[10:18:10.360] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:10.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:149:0) after 1m0s
[10:19:10.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:149:0)
[10:19:10.437] Timer tick, asking Buildbucket for the build status
[10:19:10.499] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:10.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:150:0) after 1m0s
[10:20:10.520] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:150:0)
[10:20:10.520] Timer tick, asking Buildbucket for the build status
[10:20:10.712] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:10.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:151:0) after 1m0s
[10:21:10.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:151:0)
[10:21:10.754] Timer tick, asking Buildbucket for the build status
[10:21:10.999] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:10.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:152:0) after 1m0s
[10:22:11.085] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:152:0)
[10:22:11.085] Timer tick, asking Buildbucket for the build status
[10:22:11.198] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:11.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:153:0) after 1m0s
[10:23:11.224] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:153:0)
[10:23:11.224] Timer tick, asking Buildbucket for the build status
[10:23:11.294] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:11.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:154:0) after 1m0s
[10:24:11.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:154:0)
[10:24:11.306] Timer tick, asking Buildbucket for the build status
[10:24:11.483] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:11.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:155:0) after 1m0s
[10:25:11.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:155:0)
[10:25:11.505] Timer tick, asking Buildbucket for the build status
[10:25:11.918] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:11.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:156:0) after 1m0s
[10:26:11.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:156:0)
[10:26:11.962] Timer tick, asking Buildbucket for the build status
[10:26:12.044] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:12.044] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:157:0) after 1m0s
[10:27:12.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:157:0)
[10:27:12.125] Timer tick, asking Buildbucket for the build status
[10:27:12.421] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:12.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:158:0) after 1m0s
[10:28:12.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:158:0)
[10:28:12.502] Timer tick, asking Buildbucket for the build status
[10:28:12.627] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:12.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:159:0) after 1m0s
[10:29:12.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:159:0)
[10:29:12.656] Timer tick, asking Buildbucket for the build status
[10:29:12.732] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:12.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:160:0) after 1m0s
[10:30:12.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:160:0)
[10:30:12.745] Timer tick, asking Buildbucket for the build status
[10:30:12.816] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:12.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:161:0) after 1m0s
[10:31:12.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:161:0)
[10:31:12.911] Timer tick, asking Buildbucket for the build status
[10:31:12.997] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:12.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:162:0) after 1m0s
[10:32:13.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:162:0)
[10:32:13.072] Timer tick, asking Buildbucket for the build status
[10:32:13.184] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:13.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:163:0) after 1m0s
[10:33:13.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:163:0)
[10:33:13.293] Timer tick, asking Buildbucket for the build status
[10:33:13.516] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:13.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:164:0) after 1m0s
[10:34:13.558] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:164:0)
[10:34:13.558] Timer tick, asking Buildbucket for the build status
[10:34:13.779] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:13.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:165:0) after 1m0s
[10:35:13.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:165:0)
[10:35:13.820] Timer tick, asking Buildbucket for the build status
[10:35:13.879] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:13.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:166:0) after 1m0s
[10:36:13.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:166:0)
[10:36:13.938] Timer tick, asking Buildbucket for the build status
[10:36:14.025] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:14.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:167:0) after 1m0s
[10:37:14.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:167:0)
[10:37:14.048] Timer tick, asking Buildbucket for the build status
[10:37:14.168] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:14.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:168:0) after 1m0s
[10:38:14.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:168:0)
[10:38:14.295] Timer tick, asking Buildbucket for the build status
[10:38:14.371] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:14.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:169:0) after 1m0s
[10:39:14.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:169:0)
[10:39:14.475] Timer tick, asking Buildbucket for the build status
[10:39:14.620] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:14.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:170:0) after 1m0s
[10:40:14.680] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:170:0)
[10:40:14.680] Timer tick, asking Buildbucket for the build status
[10:40:15.574] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:15.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:171:0) after 1m0s
[10:41:15.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:171:0)
[10:41:15.632] Timer tick, asking Buildbucket for the build status
[10:41:15.727] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:15.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:172:0) after 1m0s
[10:42:15.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:172:0)
[10:42:15.763] Timer tick, asking Buildbucket for the build status
[10:42:15.894] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:15.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:173:0) after 1m0s
[10:43:16.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:173:0)
[10:43:16.016] Timer tick, asking Buildbucket for the build status
[10:43:16.067] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:16.067] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:174:0) after 1m0s
[10:44:16.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:174:0)
[10:44:16.230] Timer tick, asking Buildbucket for the build status
[10:44:16.288] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:16.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:175:0) after 1m0s
[10:45:16.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:175:0)
[10:45:16.405] Timer tick, asking Buildbucket for the build status
[10:45:16.526] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:16.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:176:0) after 1m0s
[10:46:16.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:176:0)
[10:46:16.678] Timer tick, asking Buildbucket for the build status
[10:46:16.838] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:16.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:177:0) after 1m0s
[10:47:16.916] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:177:0)
[10:47:16.916] Timer tick, asking Buildbucket for the build status
[10:47:17.003] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:17.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:178:0) after 1m0s
[10:48:17.099] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:178:0)
[10:48:17.099] Timer tick, asking Buildbucket for the build status
[10:48:17.277] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:17.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:179:0) after 1m0s
[10:49:17.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:179:0)
[10:49:17.278] Timer tick, asking Buildbucket for the build status
[10:49:17.503] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:17.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:180:0) after 1m0s
[10:50:17.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:180:0)
[10:50:17.519] Timer tick, asking Buildbucket for the build status
[10:50:17.614] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:17.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:181:0) after 1m0s
[10:51:17.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:181:0)
[10:51:17.636] Timer tick, asking Buildbucket for the build status
[10:51:17.855] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:17.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:182:0) after 1m0s
[10:52:17.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:182:0)
[10:52:17.907] Timer tick, asking Buildbucket for the build status
[10:52:18.053] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:18.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:183:0) after 1m0s
[10:53:18.131] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:183:0)
[10:53:18.131] Timer tick, asking Buildbucket for the build status
[10:53:18.193] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:18.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:184:0) after 1m0s
[10:54:18.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:184:0)
[10:54:18.429] Timer tick, asking Buildbucket for the build status
[10:54:18.547] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:18.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:185:0) after 1m0s
[10:55:18.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:185:0)
[10:55:18.760] Timer tick, asking Buildbucket for the build status
[10:55:19.221] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:19.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:186:0) after 1m0s
[10:56:19.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:186:0)
[10:56:19.524] Timer tick, asking Buildbucket for the build status
[10:56:19.612] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:19.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:187:0) after 1m0s
[10:57:19.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:187:0)
[10:57:19.818] Timer tick, asking Buildbucket for the build status
[10:57:20.100] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:20.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:188:0) after 1m0s
[10:58:20.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:188:0)
[10:58:20.148] Timer tick, asking Buildbucket for the build status
[10:58:20.256] Build 8856334071719880784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:20.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021764133852339456:189:0) after 1m0s
[10:58:33.540] Received PubSub notification, asking Buildbucket for the build status
[10:58:33.949] Build 8856334071719880784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:58:33.949] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612349912911666",
"created_by": "project:chromium",
"created_ts": "1612338680495573",
"id": "8856334071719880784",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\"}}",
"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\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\", \"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-ksg6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1525, \"got_angle_revision\": \"6cc845bb0a603587ac36b900558d483e05f02f8c\", \"got_dawn_revision\": \"fd5fd05db039710c797ee8bac5e6d999173e5fc3\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\", \"got_revision_cp\": \"refs/heads/master@{#850007}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"51045556acd0fe0a1b687ef00725a57f0f19a91b\", \"got_v8_revision\": \"53720ace941d84ac17ae67a94166a363dcf0cacf\", \"got_v8_revision_cp\": \"refs/heads/9.0.123@{#1}\", \"got_webrtc_revision\": \"e7c79fd3d66e0f6c54c027f880a8b1682ebae58a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33124}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4\"}, \"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-2-ksg6\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5676-c76598c\"], \"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": "1612338724509135",
"status": "COMPLETED",
"status_changed_ts": "1612349913394953",
"tags": [
"build_address:luci.chromium.ci/win-official/1525",
"builder:win-official",
"buildset:commit/git/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b006f95bb7930f7f4c3c4a23355b5d5e06ffca4",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021764133852339456",
"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/8856334071719880784/+/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:517fb15f29bd2b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612349913399298",
"url": "https://ci.chromium.org/b/8856334071719880784",
"utcnow_ts": "1612349913917447"
}
}
[10:58:33.949] Invocation finished in 3h7m14.87180077s with status SUCCEEDED