[11:56:04.900] New invocation is queued and will start shortly
[11:56:06.057] Starting the invocation (attempt 1)
[11:56:06.098] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:56:06.099] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:56:06.099] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034160519075936560",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"818f45b67730e3ea56ea3946f27eeda2f53c168d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9034160519075936560",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/818f45b67730e3ea56ea3946f27eeda2f53c168d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/818f45b67730e3ea56ea3946f27eeda2f53c168d",
"gitiles_ref:refs/heads/master"
]
}
[11:56:07.058] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1600516566231791",
"id": "8868730457006195472",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"818f45b67730e3ea56ea3946f27eeda2f53c168d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600516566860147",
"tags": [
"build_address:luci.chromium.ci/win32-official/564",
"builder:win32-official",
"buildset:commit/git/818f45b67730e3ea56ea3946f27eeda2f53c168d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/818f45b67730e3ea56ea3946f27eeda2f53c168d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034160519075936560",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8868730457006195472/+/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": "1600516566860444",
"url": "https://ci.chromium.org/b/8868730457006195472",
"utcnow_ts": "1600516567049415"
}
}
[11:56:07.058] Task URL: https://ci.chromium.org/b/8868730457006195472
[11:56:07.058] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:2:0) after 1m0s
[11:57:07.166] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:2:0)
[11:57:07.166] Timer tick, asking Buildbucket for the build status
[11:57:07.265] Build 8868730457006195472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:07.265] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:3:0) after 1m0s
[11:58:07.296] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:3:0)
[11:58:07.296] Timer tick, asking Buildbucket for the build status
[11:58:07.631] Build 8868730457006195472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:07.631] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:4:0) after 1m0s
[11:59:07.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:4:0)
[11:59:07.700] Timer tick, asking Buildbucket for the build status
[11:59:08.380] Build 8868730457006195472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:08.380] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:5:0) after 1m0s
[12:00:08.469] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:5:0)
[12:00:08.469] Timer tick, asking Buildbucket for the build status
[12:00:08.955] Build 8868730457006195472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:00:08.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:6:0) after 1m0s
[12:01:08.997] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:6:0)
[12:01:08.997] Timer tick, asking Buildbucket for the build status
[12:01:09.211] Build 8868730457006195472: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:01:09.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:7:0) after 1m0s
[12:02:10.130] Received PubSub notification, asking Buildbucket for the build status
[12:02:10.325] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:10.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:7:0)
[12:02:10.447] Timer tick, asking Buildbucket for the build status
[12:02:10.523] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:10.524] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:9:0) after 1m0s
[12:03:10.590] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:9:0)
[12:03:10.590] Timer tick, asking Buildbucket for the build status
[12:03:10.835] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:10.835] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:10:0) after 1m0s
[12:04:11.001] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:10:0)
[12:04:11.001] Timer tick, asking Buildbucket for the build status
[12:04:11.075] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:11.075] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:11:0) after 1m0s
[12:05:11.182] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:11:0)
[12:05:11.182] Timer tick, asking Buildbucket for the build status
[12:05:11.286] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:11.286] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:12:0) after 1m0s
[12:06:11.487] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:12:0)
[12:06:11.487] Timer tick, asking Buildbucket for the build status
[12:06:11.935] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:11.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:13:0) after 1m0s
[12:07:12.213] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:13:0)
[12:07:12.213] Timer tick, asking Buildbucket for the build status
[12:07:12.320] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:12.320] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:14:0) after 1m0s
[12:08:12.434] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:14:0)
[12:08:12.434] Timer tick, asking Buildbucket for the build status
[12:08:12.542] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:12.542] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:15:0) after 1m0s
[12:09:12.757] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:15:0)
[12:09:12.757] Timer tick, asking Buildbucket for the build status
[12:09:12.847] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:12.847] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:16:0) after 1m0s
[12:10:13.014] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:16:0)
[12:10:13.014] Timer tick, asking Buildbucket for the build status
[12:10:13.114] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:13.114] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:17:0) after 1m0s
[12:11:13.221] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:17:0)
[12:11:13.221] Timer tick, asking Buildbucket for the build status
[12:11:13.338] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:13.338] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:18:0) after 1m0s
[12:12:13.366] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:18:0)
[12:12:13.366] Timer tick, asking Buildbucket for the build status
[12:12:13.501] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:13.501] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:19:0) after 1m0s
[12:13:13.550] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:19:0)
[12:13:13.550] Timer tick, asking Buildbucket for the build status
[12:13:13.645] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:13.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:20:0) after 1m0s
[12:14:13.711] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:20:0)
[12:14:13.711] Timer tick, asking Buildbucket for the build status
[12:14:13.912] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:13.912] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:21:0) after 1m0s
[12:15:13.980] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:21:0)
[12:15:13.980] Timer tick, asking Buildbucket for the build status
[12:15:14.461] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:14.461] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:22:0) after 1m0s
[12:16:14.780] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:22:0)
[12:16:14.780] Timer tick, asking Buildbucket for the build status
[12:16:15.210] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:15.210] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:23:0) after 1m0s
[12:17:15.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:23:0)
[12:17:15.278] Timer tick, asking Buildbucket for the build status
[12:17:15.709] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:15.709] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:24:0) after 1m0s
[12:18:15.976] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:24:0)
[12:18:15.976] Timer tick, asking Buildbucket for the build status
[12:18:16.043] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:16.043] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:25:0) after 1m0s
[12:19:16.072] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:25:0)
[12:19:16.072] Timer tick, asking Buildbucket for the build status
[12:19:16.208] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:16.208] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:26:0) after 1m0s
[12:20:16.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:26:0)
[12:20:16.263] Timer tick, asking Buildbucket for the build status
[12:20:16.339] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:16.339] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:27:0) after 1m0s
[12:21:16.409] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:27:0)
[12:21:16.409] Timer tick, asking Buildbucket for the build status
[12:21:17.046] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:17.046] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:28:0) after 1m0s
[12:22:17.191] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:28:0)
[12:22:17.191] Timer tick, asking Buildbucket for the build status
[12:22:17.356] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:17.356] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:29:0) after 1m0s
[12:23:17.419] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:29:0)
[12:23:17.419] Timer tick, asking Buildbucket for the build status
[12:23:17.486] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:17.486] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:30:0) after 1m0s
[12:24:17.557] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:30:0)
[12:24:17.557] Timer tick, asking Buildbucket for the build status
[12:24:17.716] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:17.716] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:31:0) after 1m0s
[12:25:17.768] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:31:0)
[12:25:17.768] Timer tick, asking Buildbucket for the build status
[12:25:17.844] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:17.844] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:32:0) after 1m0s
[12:26:17.981] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:32:0)
[12:26:17.981] Timer tick, asking Buildbucket for the build status
[12:26:18.201] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:18.201] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:33:0) after 1m0s
[12:27:18.226] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:33:0)
[12:27:18.226] Timer tick, asking Buildbucket for the build status
[12:27:18.390] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:18.391] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:34:0) after 1m0s
[12:28:18.434] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:34:0)
[12:28:18.434] Timer tick, asking Buildbucket for the build status
[12:28:18.684] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:18.684] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:35:0) after 1m0s
[12:29:18.868] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:35:0)
[12:29:18.868] Timer tick, asking Buildbucket for the build status
[12:29:19.200] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:19.200] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:36:0) after 1m0s
[12:30:19.393] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:36:0)
[12:30:19.393] Timer tick, asking Buildbucket for the build status
[12:30:19.460] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:19.460] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:37:0) after 1m0s
[12:31:19.463] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:37:0)
[12:31:19.463] Timer tick, asking Buildbucket for the build status
[12:31:19.971] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:19.971] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:38:0) after 1m0s
[12:32:20.610] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:38:0)
[12:32:20.610] Timer tick, asking Buildbucket for the build status
[12:32:20.733] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:20.733] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:39:0) after 1m0s
[12:33:20.839] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:39:0)
[12:33:20.839] Timer tick, asking Buildbucket for the build status
[12:33:20.946] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:20.946] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:40:0) after 1m0s
[12:34:21.046] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:40:0)
[12:34:21.046] Timer tick, asking Buildbucket for the build status
[12:34:21.340] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:21.340] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:41:0) after 1m0s
[12:35:21.570] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:41:0)
[12:35:21.570] Timer tick, asking Buildbucket for the build status
[12:35:21.683] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:21.683] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:42:0) after 1m0s
[12:36:21.895] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:42:0)
[12:36:21.895] Timer tick, asking Buildbucket for the build status
[12:36:22.413] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:22.414] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:43:0) after 1m0s
[12:37:22.679] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:43:0)
[12:37:22.679] Timer tick, asking Buildbucket for the build status
[12:37:22.899] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:22.899] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:44:0) after 1m0s
[12:38:23.155] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:44:0)
[12:38:23.155] Timer tick, asking Buildbucket for the build status
[12:38:23.290] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:23.290] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:45:0) after 1m0s
[12:39:23.353] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:45:0)
[12:39:23.353] Timer tick, asking Buildbucket for the build status
[12:39:23.440] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:23.440] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:46:0) after 1m0s
[12:40:23.580] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:46:0)
[12:40:23.580] Timer tick, asking Buildbucket for the build status
[12:40:23.659] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:23.659] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:47:0) after 1m0s
[12:41:23.904] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:47:0)
[12:41:23.904] Timer tick, asking Buildbucket for the build status
[12:41:24.235] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:24.235] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:48:0) after 1m0s
[12:42:24.256] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:48:0)
[12:42:24.256] Timer tick, asking Buildbucket for the build status
[12:42:24.322] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:24.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:49:0) after 1m0s
[12:43:24.568] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:49:0)
[12:43:24.568] Timer tick, asking Buildbucket for the build status
[12:43:24.805] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:24.805] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:50:0) after 1m0s
[12:44:24.985] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:50:0)
[12:44:24.985] Timer tick, asking Buildbucket for the build status
[12:44:25.069] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:25.069] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:51:0) after 1m0s
[12:45:25.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:51:0)
[12:45:25.084] Timer tick, asking Buildbucket for the build status
[12:45:25.221] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:25.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:52:0) after 1m0s
[12:46:25.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:52:0)
[12:46:25.521] Timer tick, asking Buildbucket for the build status
[12:46:25.830] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:25.832] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:53:0) after 1m0s
[12:47:25.903] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:53:0)
[12:47:25.904] Timer tick, asking Buildbucket for the build status
[12:47:25.968] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:25.968] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:54:0) after 1m0s
[12:48:26.155] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:54:0)
[12:48:26.155] Timer tick, asking Buildbucket for the build status
[12:48:26.340] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:26.354] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:55:0) after 1m0s
[12:49:26.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:55:0)
[12:49:26.573] Timer tick, asking Buildbucket for the build status
[12:49:26.950] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:26.950] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:56:0) after 1m0s
[12:50:27.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:56:0)
[12:50:27.008] Timer tick, asking Buildbucket for the build status
[12:50:27.298] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:27.298] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:57:0) after 1m0s
[12:51:27.322] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:57:0)
[12:51:27.322] Timer tick, asking Buildbucket for the build status
[12:51:27.454] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:27.454] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:58:0) after 1m0s
[12:52:27.586] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:58:0)
[12:52:27.586] Timer tick, asking Buildbucket for the build status
[12:52:27.728] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:27.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:59:0) after 1m0s
[12:53:27.954] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:59:0)
[12:53:27.954] Timer tick, asking Buildbucket for the build status
[12:53:28.024] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:28.024] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:60:0) after 1m0s
[12:54:28.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:60:0)
[12:54:28.173] Timer tick, asking Buildbucket for the build status
[12:54:28.291] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:28.291] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:61:0) after 1m0s
[12:55:28.483] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:61:0)
[12:55:28.483] Timer tick, asking Buildbucket for the build status
[12:55:28.556] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:28.556] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:62:0) after 1m0s
[12:56:28.604] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:62:0)
[12:56:28.604] Timer tick, asking Buildbucket for the build status
[12:56:28.728] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:28.728] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:63:0) after 1m0s
[12:57:28.891] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:63:0)
[12:57:28.891] Timer tick, asking Buildbucket for the build status
[12:57:29.021] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:29.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:64:0) after 1m0s
[12:58:29.152] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:64:0)
[12:58:29.152] Timer tick, asking Buildbucket for the build status
[12:58:29.237] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:29.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:65:0) after 1m0s
[12:59:29.307] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:65:0)
[12:59:29.307] Timer tick, asking Buildbucket for the build status
[12:59:29.397] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:29.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:66:0) after 1m0s
[13:00:29.517] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:66:0)
[13:00:29.517] Timer tick, asking Buildbucket for the build status
[13:00:29.586] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:29.586] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:67:0) after 1m0s
[13:01:29.623] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:67:0)
[13:01:29.623] Timer tick, asking Buildbucket for the build status
[13:01:29.740] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:29.740] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:68:0) after 1m0s
[13:02:29.748] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:68:0)
[13:02:29.748] Timer tick, asking Buildbucket for the build status
[13:02:30.057] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:30.057] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:69:0) after 1m0s
[13:03:30.126] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:69:0)
[13:03:30.126] Timer tick, asking Buildbucket for the build status
[13:03:30.203] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:30.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:70:0) after 1m0s
[13:04:30.230] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:70:0)
[13:04:30.230] Timer tick, asking Buildbucket for the build status
[13:04:30.390] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:30.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:71:0) after 1m0s
[13:05:30.478] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:71:0)
[13:05:30.478] Timer tick, asking Buildbucket for the build status
[13:05:30.552] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:30.552] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:72:0) after 1m0s
[13:06:30.784] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:72:0)
[13:06:30.784] Timer tick, asking Buildbucket for the build status
[13:06:30.912] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:30.912] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:73:0) after 1m0s
[13:07:30.945] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:73:0)
[13:07:30.945] Timer tick, asking Buildbucket for the build status
[13:07:31.016] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:31.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:74:0) after 1m0s
[13:08:31.073] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:74:0)
[13:08:31.073] Timer tick, asking Buildbucket for the build status
[13:08:31.192] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:31.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:75:0) after 1m0s
[13:09:31.261] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:75:0)
[13:09:31.261] Timer tick, asking Buildbucket for the build status
[13:09:31.421] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:31.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:76:0) after 1m0s
[13:10:31.494] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:76:0)
[13:10:31.494] Timer tick, asking Buildbucket for the build status
[13:10:31.742] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:31.742] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:77:0) after 1m0s
[13:11:31.760] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:77:0)
[13:11:31.760] Timer tick, asking Buildbucket for the build status
[13:11:31.906] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:31.906] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:78:0) after 1m0s
[13:12:31.937] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:78:0)
[13:12:31.937] Timer tick, asking Buildbucket for the build status
[13:12:32.209] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:32.209] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:79:0) after 1m0s
[13:13:32.244] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:79:0)
[13:13:32.244] Timer tick, asking Buildbucket for the build status
[13:13:32.307] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:32.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:80:0) after 1m0s
[13:14:32.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:80:0)
[13:14:32.342] Timer tick, asking Buildbucket for the build status
[13:14:32.527] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:32.527] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:81:0) after 1m0s
[13:15:32.609] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:81:0)
[13:15:32.609] Timer tick, asking Buildbucket for the build status
[13:15:33.108] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:33.110] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:82:0) after 1m0s
[13:16:33.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:82:0)
[13:16:33.209] Timer tick, asking Buildbucket for the build status
[13:16:33.353] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:33.353] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:83:0) after 1m0s
[13:17:33.368] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:83:0)
[13:17:33.368] Timer tick, asking Buildbucket for the build status
[13:17:33.626] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:33.626] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:84:0) after 1m0s
[13:18:33.676] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:84:0)
[13:18:33.676] Timer tick, asking Buildbucket for the build status
[13:18:33.815] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:33.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:85:0) after 1m0s
[13:19:33.866] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:85:0)
[13:19:33.866] Timer tick, asking Buildbucket for the build status
[13:19:34.187] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:34.187] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:86:0) after 1m0s
[13:20:34.340] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:86:0)
[13:20:34.340] Timer tick, asking Buildbucket for the build status
[13:20:34.405] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:34.405] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:87:0) after 1m0s
[13:21:34.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:87:0)
[13:21:34.561] Timer tick, asking Buildbucket for the build status
[13:21:34.699] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:34.699] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:88:0) after 1m0s
[13:22:34.778] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:88:0)
[13:22:34.778] Timer tick, asking Buildbucket for the build status
[13:22:34.946] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:34.946] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:89:0) after 1m0s
[13:23:34.984] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:89:0)
[13:23:34.984] Timer tick, asking Buildbucket for the build status
[13:23:35.072] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:35.072] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:90:0) after 1m0s
[13:24:35.112] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:90:0)
[13:24:35.112] Timer tick, asking Buildbucket for the build status
[13:24:35.184] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:35.184] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:91:0) after 1m0s
[13:25:35.214] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:91:0)
[13:25:35.214] Timer tick, asking Buildbucket for the build status
[13:25:35.352] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:35.352] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:92:0) after 1m0s
[13:26:35.433] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:92:0)
[13:26:35.433] Timer tick, asking Buildbucket for the build status
[13:26:35.587] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:35.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:93:0) after 1m0s
[13:27:35.621] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:93:0)
[13:27:35.621] Timer tick, asking Buildbucket for the build status
[13:27:35.692] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:35.692] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:94:0) after 1m0s
[13:28:35.668] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:94:0)
[13:28:35.668] Timer tick, asking Buildbucket for the build status
[13:28:35.724] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:35.724] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:95:0) after 1m0s
[13:29:35.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:95:0)
[13:29:35.745] Timer tick, asking Buildbucket for the build status
[13:29:35.827] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:35.827] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:96:0) after 1m0s
[13:30:35.850] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:96:0)
[13:30:35.850] Timer tick, asking Buildbucket for the build status
[13:30:35.962] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:35.962] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:97:0) after 1m0s
[13:31:35.985] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:97:0)
[13:31:35.985] Timer tick, asking Buildbucket for the build status
[13:31:36.092] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:36.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:98:0) after 1m0s
[13:32:36.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:98:0)
[13:32:36.209] Timer tick, asking Buildbucket for the build status
[13:32:36.282] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:36.282] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:99:0) after 1m0s
[13:33:36.346] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:99:0)
[13:33:36.346] Timer tick, asking Buildbucket for the build status
[13:33:36.410] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:36.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:100:0) after 1m0s
[13:34:36.546] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:100:0)
[13:34:36.547] Timer tick, asking Buildbucket for the build status
[13:34:36.661] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:36.661] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:101:0) after 1m0s
[13:35:36.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:101:0)
[13:35:36.773] Timer tick, asking Buildbucket for the build status
[13:35:36.855] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:36.855] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:102:0) after 1m0s
[13:36:37.021] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:102:0)
[13:36:37.021] Timer tick, asking Buildbucket for the build status
[13:36:37.421] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:37.421] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:103:0) after 1m0s
[13:37:37.435] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:103:0)
[13:37:37.435] Timer tick, asking Buildbucket for the build status
[13:37:37.514] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:37.514] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:104:0) after 1m0s
[13:38:37.611] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:104:0)
[13:38:37.611] Timer tick, asking Buildbucket for the build status
[13:38:37.745] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:37.745] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:105:0) after 1m0s
[13:39:37.915] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:105:0)
[13:39:37.915] Timer tick, asking Buildbucket for the build status
[13:39:38.019] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:38.019] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:106:0) after 1m0s
[13:40:38.115] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:106:0)
[13:40:38.115] Timer tick, asking Buildbucket for the build status
[13:40:38.420] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:38.420] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:107:0) after 1m0s
[13:41:38.439] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:107:0)
[13:41:38.439] Timer tick, asking Buildbucket for the build status
[13:41:38.707] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:38.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:108:0) after 1m0s
[13:42:38.826] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:108:0)
[13:42:38.826] Timer tick, asking Buildbucket for the build status
[13:42:39.008] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:39.008] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:109:0) after 1m0s
[13:43:39.035] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:109:0)
[13:43:39.035] Timer tick, asking Buildbucket for the build status
[13:43:39.664] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:39.664] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:110:0) after 1m0s
[13:44:39.695] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:110:0)
[13:44:39.695] Timer tick, asking Buildbucket for the build status
[13:44:39.754] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:39.754] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:111:0) after 1m0s
[13:45:39.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:111:0)
[13:45:39.804] Timer tick, asking Buildbucket for the build status
[13:45:39.882] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:39.882] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:112:0) after 1m0s
[13:46:39.973] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:112:0)
[13:46:39.973] Timer tick, asking Buildbucket for the build status
[13:46:40.095] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:40.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:113:0) after 1m0s
[13:47:40.141] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:113:0)
[13:47:40.141] Timer tick, asking Buildbucket for the build status
[13:47:40.293] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:40.293] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:114:0) after 1m0s
[13:48:40.385] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:114:0)
[13:48:40.385] Timer tick, asking Buildbucket for the build status
[13:48:42.192] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:42.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:115:0) after 1m0s
[13:49:42.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:115:0)
[13:49:42.263] Timer tick, asking Buildbucket for the build status
[13:49:42.442] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:42.442] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:116:0) after 1m0s
[13:50:42.491] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:116:0)
[13:50:42.491] Timer tick, asking Buildbucket for the build status
[13:50:42.579] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:42.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:117:0) after 1m0s
[13:51:42.685] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:117:0)
[13:51:42.685] Timer tick, asking Buildbucket for the build status
[13:51:42.797] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:42.797] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:118:0) after 1m0s
[13:52:42.844] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:118:0)
[13:52:42.844] Timer tick, asking Buildbucket for the build status
[13:52:42.909] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:42.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:119:0) after 1m0s
[13:53:42.937] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:119:0)
[13:53:42.937] Timer tick, asking Buildbucket for the build status
[13:53:43.088] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:43.088] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:120:0) after 1m0s
[13:54:43.126] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:120:0)
[13:54:43.126] Timer tick, asking Buildbucket for the build status
[13:54:43.192] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:43.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:121:0) after 1m0s
[13:55:43.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:121:0)
[13:55:43.317] Timer tick, asking Buildbucket for the build status
[13:55:43.418] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:43.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:122:0) after 1m0s
[13:56:43.489] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:122:0)
[13:56:43.489] Timer tick, asking Buildbucket for the build status
[13:56:43.632] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:43.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:123:0) after 1m0s
[13:57:43.665] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:123:0)
[13:57:43.665] Timer tick, asking Buildbucket for the build status
[13:57:43.746] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:43.746] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:124:0) after 1m0s
[13:58:43.796] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:124:0)
[13:58:43.796] Timer tick, asking Buildbucket for the build status
[13:58:43.860] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:43.860] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:125:0) after 1m0s
[13:59:44.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:125:0)
[13:59:44.034] Timer tick, asking Buildbucket for the build status
[13:59:44.380] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:44.380] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:126:0) after 1m0s
[14:00:44.438] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:126:0)
[14:00:44.438] Timer tick, asking Buildbucket for the build status
[14:00:44.521] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:44.521] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:127:0) after 1m0s
[14:01:45.143] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:127:0)
[14:01:45.143] Timer tick, asking Buildbucket for the build status
[14:01:45.485] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:45.485] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:128:0) after 1m0s
[14:02:45.760] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:128:0)
[14:02:45.760] Timer tick, asking Buildbucket for the build status
[14:02:45.968] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:45.968] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:129:0) after 1m0s
[14:03:46.820] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:129:0)
[14:03:46.820] Timer tick, asking Buildbucket for the build status
[14:03:46.891] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:46.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:130:0) after 1m0s
[14:04:47.525] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:130:0)
[14:04:47.525] Timer tick, asking Buildbucket for the build status
[14:04:47.724] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:47.724] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:131:0) after 1m0s
[14:05:48.003] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:131:0)
[14:05:48.003] Timer tick, asking Buildbucket for the build status
[14:05:48.063] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:48.063] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:132:0) after 1m0s
[14:06:48.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:132:0)
[14:06:48.387] Timer tick, asking Buildbucket for the build status
[14:06:48.496] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:48.496] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:133:0) after 1m0s
[14:07:48.645] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:133:0)
[14:07:48.645] Timer tick, asking Buildbucket for the build status
[14:07:48.798] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:48.798] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:134:0) after 1m0s
[14:08:48.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:134:0)
[14:08:48.871] Timer tick, asking Buildbucket for the build status
[14:08:49.322] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:49.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:135:0) after 1m0s
[14:09:49.447] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:135:0)
[14:09:49.447] Timer tick, asking Buildbucket for the build status
[14:09:49.709] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:49.709] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:136:0) after 1m0s
[14:10:49.807] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:136:0)
[14:10:49.807] Timer tick, asking Buildbucket for the build status
[14:10:49.925] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:49.925] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:137:0) after 1m0s
[14:11:49.995] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:137:0)
[14:11:49.995] Timer tick, asking Buildbucket for the build status
[14:11:50.289] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:50.289] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:138:0) after 1m0s
[14:12:50.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:138:0)
[14:12:50.387] Timer tick, asking Buildbucket for the build status
[14:12:50.670] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:50.670] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:139:0) after 1m0s
[14:13:50.744] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:139:0)
[14:13:50.744] Timer tick, asking Buildbucket for the build status
[14:13:50.827] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:50.827] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:140:0) after 1m0s
[14:14:51.012] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:140:0)
[14:14:51.019] Timer tick, asking Buildbucket for the build status
[14:14:51.092] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:51.093] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:141:0) after 1m0s
[14:15:51.118] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:141:0)
[14:15:51.118] Timer tick, asking Buildbucket for the build status
[14:15:51.176] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:51.176] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:142:0) after 1m0s
[14:16:52.784] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:142:0)
[14:16:52.784] Timer tick, asking Buildbucket for the build status
[14:16:52.935] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:52.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:143:0) after 1m0s
[14:17:53.270] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:143:0)
[14:17:53.270] Timer tick, asking Buildbucket for the build status
[14:17:53.349] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:53.349] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:144:0) after 1m0s
[14:18:53.612] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:144:0)
[14:18:53.612] Timer tick, asking Buildbucket for the build status
[14:18:55.044] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:55.044] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:145:0) after 1m0s
[14:19:55.148] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:145:0)
[14:19:55.148] Timer tick, asking Buildbucket for the build status
[14:19:55.226] Build 8868730457006195472: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:55.226] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034160519075936560:146:0) after 1m0s
[14:20:31.087] Received PubSub notification, asking Buildbucket for the build status
[14:20:31.152] Build 8868730457006195472: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:20:31.153] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1600525229974741",
"created_by": "project:chromium",
"created_ts": "1600516566231791",
"id": "8868730457006195472",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"818f45b67730e3ea56ea3946f27eeda2f53c168d\"}}",
"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\": \"818f45b67730e3ea56ea3946f27eeda2f53c168d\", \"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-5-4shi\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 564, \"got_angle_revision\": \"f6f206018caf4008d52a311fe07466ad08bef808\", \"got_dawn_revision\": \"dcd37fa4f47663d1a68c1c7fb10738b6ea0e0a4c\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"818f45b67730e3ea56ea3946f27eeda2f53c168d\", \"got_revision_cp\": \"refs/heads/master@{#808681}\", \"got_swarming_client_revision\": \"44c13d73156581ea09b9389001e58c23a4b8d70a\", \"got_swiftshader_revision\": \"fe878dedd5addbedab4033c0cb6b19bbbe04f8e4\", \"got_v8_revision\": \"80dc1e8055bca07e8da6162bace5ee87f23faccf\", \"got_v8_revision_cp\": \"refs/heads/8.7.142@{#1}\", \"got_webrtc_revision\": \"14dbf82961c32ae9133bb672c88e146942062ada\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32143}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"818f45b67730e3ea56ea3946f27eeda2f53c168d\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_cc946d8c9fe3204026095d74248289703a45d938e0b9766be9cb626e466da04f_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-5-4shi\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"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\": [\"5531-8ab19d9\"], \"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": "1600516929420858",
"status": "COMPLETED",
"status_changed_ts": "1600525230515613",
"tags": [
"build_address:luci.chromium.ci/win32-official/564",
"builder:win32-official",
"buildset:commit/git/818f45b67730e3ea56ea3946f27eeda2f53c168d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/818f45b67730e3ea56ea3946f27eeda2f53c168d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034160519075936560",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8868730457006195472/+/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:4ebf0a3978aace10",
"user_agent:luci-scheduler"
],
"updated_ts": "1600525230533106",
"url": "https://ci.chromium.org/b/8868730457006195472",
"utcnow_ts": "1600525231145048"
}
}
[14:20:31.153] Invocation finished in 2h24m26.279528441s with status SUCCEEDED