[08:42:10.341] New invocation is queued and will start shortly
[08:42:11.946] Starting the invocation (attempt 1)
[08:42:12.003] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:42:12.003] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:42:12.003] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034082121818392736",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f502f40a89a3e35fcee2048b5a355c47a81280cb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9034082121818392736",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/f502f40a89a3e35fcee2048b5a355c47a81280cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f502f40a89a3e35fcee2048b5a355c47a81280cb",
"gitiles_ref:refs/heads/master"
]
}
[08:42:13.324] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1600591332261852",
"id": "8868652059141131600",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f502f40a89a3e35fcee2048b5a355c47a81280cb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1600591333173405",
"tags": [
"build_address:luci.chromium.ci/win32-official/571",
"builder:win32-official",
"buildset:commit/git/f502f40a89a3e35fcee2048b5a355c47a81280cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f502f40a89a3e35fcee2048b5a355c47a81280cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034082121818392736",
"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/8868652059141131600/+/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": "1600591333173585",
"url": "https://ci.chromium.org/b/8868652059141131600",
"utcnow_ts": "1600591333313163"
}
}
[08:42:13.324] Task URL: https://ci.chromium.org/b/8868652059141131600
[08:42:13.324] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:2:0) after 1m0s
[08:42:53.938] Received PubSub notification, asking Buildbucket for the build status
[08:42:54.317] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:13.392] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:2:0)
[08:43:13.392] Timer tick, asking Buildbucket for the build status
[08:43:13.534] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:13.534] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:4:0) after 1m0s
[08:44:13.588] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:4:0)
[08:44:13.588] Timer tick, asking Buildbucket for the build status
[08:44:13.664] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:13.664] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:5:0) after 1m0s
[08:45:13.701] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:5:0)
[08:45:13.702] Timer tick, asking Buildbucket for the build status
[08:45:13.805] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:13.805] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:6:0) after 1m0s
[08:46:13.835] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:6:0)
[08:46:13.835] Timer tick, asking Buildbucket for the build status
[08:46:13.961] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:13.962] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:7:0) after 1m0s
[08:47:14.015] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:7:0)
[08:47:14.015] Timer tick, asking Buildbucket for the build status
[08:47:14.345] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:14.345] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:8:0) after 1m0s
[08:48:14.392] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:8:0)
[08:48:14.392] Timer tick, asking Buildbucket for the build status
[08:48:14.550] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:14.550] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:9:0) after 1m0s
[08:49:14.609] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:9:0)
[08:49:14.609] Timer tick, asking Buildbucket for the build status
[08:49:14.712] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:14.712] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:10:0) after 1m0s
[08:50:14.857] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:10:0)
[08:50:14.857] Timer tick, asking Buildbucket for the build status
[08:50:15.122] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:15.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:11:0) after 1m0s
[08:51:15.158] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:11:0)
[08:51:15.158] Timer tick, asking Buildbucket for the build status
[08:51:15.760] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:15.760] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:12:0) after 1m0s
[08:52:15.816] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:12:0)
[08:52:15.816] Timer tick, asking Buildbucket for the build status
[08:52:16.286] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:16.286] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:13:0) after 1m0s
[08:53:16.486] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:13:0)
[08:53:16.486] Timer tick, asking Buildbucket for the build status
[08:53:16.570] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:16.570] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:14:0) after 1m0s
[08:54:16.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:14:0)
[08:54:16.640] Timer tick, asking Buildbucket for the build status
[08:54:16.896] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:16.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:15:0) after 1m0s
[08:55:17.006] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:15:0)
[08:55:17.006] Timer tick, asking Buildbucket for the build status
[08:55:17.119] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:17.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:16:0) after 1m0s
[08:56:17.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:16:0)
[08:56:17.156] Timer tick, asking Buildbucket for the build status
[08:56:17.223] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:17.224] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:17:0) after 1m0s
[08:57:17.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:17:0)
[08:57:17.342] Timer tick, asking Buildbucket for the build status
[08:57:17.455] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:17.455] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:18:0) after 1m0s
[08:58:17.515] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:18:0)
[08:58:17.515] Timer tick, asking Buildbucket for the build status
[08:58:17.579] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:17.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:19:0) after 1m0s
[08:59:17.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:19:0)
[08:59:17.613] Timer tick, asking Buildbucket for the build status
[08:59:17.676] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:17.676] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:20:0) after 1m0s
[09:00:17.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:20:0)
[09:00:17.700] Timer tick, asking Buildbucket for the build status
[09:00:17.879] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:17.879] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:21:0) after 1m0s
[09:01:17.917] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:21:0)
[09:01:17.917] Timer tick, asking Buildbucket for the build status
[09:01:18.254] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:18.254] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:22:0) after 1m0s
[09:02:18.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:22:0)
[09:02:18.348] Timer tick, asking Buildbucket for the build status
[09:02:18.445] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:18.446] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:23:0) after 1m0s
[09:03:18.482] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:23:0)
[09:03:18.482] Timer tick, asking Buildbucket for the build status
[09:03:18.550] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:18.550] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:24:0) after 1m0s
[09:04:18.575] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:24:0)
[09:04:18.575] Timer tick, asking Buildbucket for the build status
[09:04:18.664] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:18.664] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:25:0) after 1m0s
[09:05:18.689] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:25:0)
[09:05:18.689] Timer tick, asking Buildbucket for the build status
[09:05:18.749] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:18.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:26:0) after 1m0s
[09:06:18.794] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:26:0)
[09:06:18.794] Timer tick, asking Buildbucket for the build status
[09:06:18.870] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:18.871] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:27:0) after 1m0s
[09:07:18.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:27:0)
[09:07:18.961] Timer tick, asking Buildbucket for the build status
[09:07:19.093] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:19.093] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:28:0) after 1m0s
[09:08:19.199] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:28:0)
[09:08:19.199] Timer tick, asking Buildbucket for the build status
[09:08:19.303] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:19.304] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:29:0) after 1m0s
[09:09:19.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:29:0)
[09:09:19.344] Timer tick, asking Buildbucket for the build status
[09:09:19.422] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:19.422] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:30:0) after 1m0s
[09:10:19.484] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:30:0)
[09:10:19.484] Timer tick, asking Buildbucket for the build status
[09:10:19.552] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:19.552] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:31:0) after 1m0s
[09:11:19.554] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:31:0)
[09:11:19.554] Timer tick, asking Buildbucket for the build status
[09:11:19.711] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:19.712] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:32:0) after 1m0s
[09:12:19.736] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:32:0)
[09:12:19.736] Timer tick, asking Buildbucket for the build status
[09:12:20.128] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:20.128] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:33:0) after 1m0s
[09:13:20.223] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:33:0)
[09:13:20.223] Timer tick, asking Buildbucket for the build status
[09:13:20.328] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:20.328] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:34:0) after 1m0s
[09:14:20.386] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:34:0)
[09:14:20.386] Timer tick, asking Buildbucket for the build status
[09:14:20.614] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:20.614] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:35:0) after 1m0s
[09:15:20.704] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:35:0)
[09:15:20.705] Timer tick, asking Buildbucket for the build status
[09:15:20.898] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:20.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:36:0) after 1m0s
[09:16:20.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:36:0)
[09:16:20.983] Timer tick, asking Buildbucket for the build status
[09:16:21.051] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:21.051] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:37:0) after 1m0s
[09:17:21.231] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:37:0)
[09:17:21.231] Timer tick, asking Buildbucket for the build status
[09:17:21.401] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:21.401] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:38:0) after 1m0s
[09:18:21.446] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:38:0)
[09:18:21.446] Timer tick, asking Buildbucket for the build status
[09:18:21.749] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:21.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:39:0) after 1m0s
[09:19:21.786] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:39:0)
[09:19:21.786] Timer tick, asking Buildbucket for the build status
[09:19:22.040] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:22.040] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:40:0) after 1m0s
[09:20:22.078] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:40:0)
[09:20:22.078] Timer tick, asking Buildbucket for the build status
[09:20:22.154] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:22.154] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:41:0) after 1m0s
[09:21:22.266] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:41:0)
[09:21:22.266] Timer tick, asking Buildbucket for the build status
[09:21:22.540] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:22.540] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:42:0) after 1m0s
[09:22:22.563] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:42:0)
[09:22:22.563] Timer tick, asking Buildbucket for the build status
[09:22:23.030] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:23.030] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:43:0) after 1m0s
[09:23:23.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:43:0)
[09:23:23.361] Timer tick, asking Buildbucket for the build status
[09:23:23.693] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:23.693] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:44:0) after 1m0s
[09:24:24.828] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:44:0)
[09:24:24.828] Timer tick, asking Buildbucket for the build status
[09:24:24.890] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:24.890] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:45:0) after 1m0s
[09:25:24.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:45:0)
[09:25:24.925] Timer tick, asking Buildbucket for the build status
[09:25:25.105] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:25.105] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:46:0) after 1m0s
[09:26:30.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:46:0)
[09:26:30.453] Timer tick, asking Buildbucket for the build status
[09:26:30.804] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:30.804] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:47:0) after 1m0s
[09:27:30.977] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:47:0)
[09:27:30.977] Timer tick, asking Buildbucket for the build status
[09:27:31.097] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:31.097] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:48:0) after 1m0s
[09:28:31.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:48:0)
[09:28:31.521] Timer tick, asking Buildbucket for the build status
[09:28:31.601] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:31.601] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:49:0) after 1m0s
[09:29:31.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:49:0)
[09:29:31.925] Timer tick, asking Buildbucket for the build status
[09:29:31.990] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:31.990] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:50:0) after 1m0s
[09:30:32.163] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:50:0)
[09:30:32.163] Timer tick, asking Buildbucket for the build status
[09:30:32.232] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:32.232] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:51:0) after 1m0s
[09:31:32.349] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:51:0)
[09:31:32.349] Timer tick, asking Buildbucket for the build status
[09:31:32.412] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:32.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:52:0) after 1m0s
[09:32:32.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:52:0)
[09:32:32.505] Timer tick, asking Buildbucket for the build status
[09:32:32.708] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:32.708] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:53:0) after 1m0s
[09:33:32.848] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:53:0)
[09:33:32.848] Timer tick, asking Buildbucket for the build status
[09:33:32.927] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:32.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:54:0) after 1m0s
[09:34:33.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:54:0)
[09:34:33.048] Timer tick, asking Buildbucket for the build status
[09:34:33.133] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:33.133] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:55:0) after 1m0s
[09:35:33.166] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:55:0)
[09:35:33.166] Timer tick, asking Buildbucket for the build status
[09:35:33.239] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:33.239] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:56:0) after 1m0s
[09:36:33.730] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:56:0)
[09:36:33.730] Timer tick, asking Buildbucket for the build status
[09:36:33.815] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:33.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:57:0) after 1m0s
[09:37:33.855] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:57:0)
[09:37:33.855] Timer tick, asking Buildbucket for the build status
[09:37:33.950] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:33.950] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:58:0) after 1m0s
[09:38:33.992] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:58:0)
[09:38:33.992] Timer tick, asking Buildbucket for the build status
[09:38:34.053] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:34.053] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:59:0) after 1m0s
[09:39:34.157] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:59:0)
[09:39:34.157] Timer tick, asking Buildbucket for the build status
[09:39:34.250] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:34.250] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:60:0) after 1m0s
[09:40:34.325] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:60:0)
[09:40:34.325] Timer tick, asking Buildbucket for the build status
[09:40:34.410] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:34.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:61:0) after 1m0s
[09:41:34.519] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:61:0)
[09:41:34.519] Timer tick, asking Buildbucket for the build status
[09:41:34.645] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:34.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:62:0) after 1m0s
[09:42:35.082] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:62:0)
[09:42:35.082] Timer tick, asking Buildbucket for the build status
[09:42:35.232] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:35.232] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:63:0) after 1m0s
[09:43:35.425] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:63:0)
[09:43:35.425] Timer tick, asking Buildbucket for the build status
[09:43:35.488] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:35.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:64:0) after 1m0s
[09:44:35.668] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:64:0)
[09:44:35.668] Timer tick, asking Buildbucket for the build status
[09:44:35.782] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:35.782] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:65:0) after 1m0s
[09:45:35.906] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:65:0)
[09:45:35.906] Timer tick, asking Buildbucket for the build status
[09:45:36.038] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:36.038] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:66:0) after 1m0s
[09:46:36.293] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:66:0)
[09:46:36.293] Timer tick, asking Buildbucket for the build status
[09:46:36.374] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:36.374] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:67:0) after 1m0s
[09:47:36.473] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:67:0)
[09:47:36.473] Timer tick, asking Buildbucket for the build status
[09:47:36.576] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:36.576] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:68:0) after 1m0s
[09:48:36.738] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:68:0)
[09:48:36.738] Timer tick, asking Buildbucket for the build status
[09:48:36.821] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:36.821] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:69:0) after 1m0s
[09:49:36.932] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:69:0)
[09:49:36.932] Timer tick, asking Buildbucket for the build status
[09:49:36.999] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:36.999] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:70:0) after 1m0s
[09:50:37.057] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:70:0)
[09:50:37.057] Timer tick, asking Buildbucket for the build status
[09:50:37.132] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:37.132] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:71:0) after 1m0s
[09:51:37.187] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:71:0)
[09:51:37.187] Timer tick, asking Buildbucket for the build status
[09:51:37.261] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:37.261] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:72:0) after 1m0s
[09:52:37.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:72:0)
[09:52:37.387] Timer tick, asking Buildbucket for the build status
[09:52:37.489] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:37.489] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:73:0) after 1m0s
[09:53:37.486] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:73:0)
[09:53:37.486] Timer tick, asking Buildbucket for the build status
[09:53:37.799] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:37.799] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:74:0) after 1m0s
[09:54:37.872] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:74:0)
[09:54:37.872] Timer tick, asking Buildbucket for the build status
[09:54:38.061] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:38.061] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:75:0) after 1m0s
[09:55:38.053] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:75:0)
[09:55:38.053] Timer tick, asking Buildbucket for the build status
[09:55:38.153] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:38.153] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:76:0) after 1m0s
[09:56:38.239] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:76:0)
[09:56:38.239] Timer tick, asking Buildbucket for the build status
[09:56:38.305] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:38.305] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:77:0) after 1m0s
[09:57:38.379] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:77:0)
[09:57:38.379] Timer tick, asking Buildbucket for the build status
[09:57:38.482] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:38.482] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:78:0) after 1m0s
[09:58:38.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:78:0)
[09:58:38.505] Timer tick, asking Buildbucket for the build status
[09:58:38.883] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:38.883] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:79:0) after 1m0s
[09:59:38.966] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:79:0)
[09:59:38.966] Timer tick, asking Buildbucket for the build status
[09:59:39.047] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:39.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:80:0) after 1m0s
[10:00:39.105] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:80:0)
[10:00:39.105] Timer tick, asking Buildbucket for the build status
[10:00:39.166] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:39.166] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:81:0) after 1m0s
[10:01:39.294] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:81:0)
[10:01:39.294] Timer tick, asking Buildbucket for the build status
[10:01:39.361] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:39.361] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:82:0) after 1m0s
[10:02:39.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:82:0)
[10:02:39.450] Timer tick, asking Buildbucket for the build status
[10:02:39.620] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:39.620] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:83:0) after 1m0s
[10:03:39.649] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:83:0)
[10:03:39.649] Timer tick, asking Buildbucket for the build status
[10:03:39.752] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:39.752] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:84:0) after 1m0s
[10:04:39.753] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:84:0)
[10:04:39.753] Timer tick, asking Buildbucket for the build status
[10:04:39.924] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:39.924] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:85:0) after 1m0s
[10:05:40.025] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:85:0)
[10:05:40.025] Timer tick, asking Buildbucket for the build status
[10:05:40.092] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:40.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:86:0) after 1m0s
[10:06:40.176] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:86:0)
[10:06:40.176] Timer tick, asking Buildbucket for the build status
[10:06:40.648] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:40.648] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:87:0) after 1m0s
[10:07:40.762] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:87:0)
[10:07:40.762] Timer tick, asking Buildbucket for the build status
[10:07:41.013] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:41.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:88:0) after 1m0s
[10:08:41.116] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:88:0)
[10:08:41.116] Timer tick, asking Buildbucket for the build status
[10:08:41.277] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:41.277] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:89:0) after 1m0s
[10:09:41.327] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:89:0)
[10:09:41.327] Timer tick, asking Buildbucket for the build status
[10:09:41.499] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:41.499] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:90:0) after 1m0s
[10:10:41.558] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:90:0)
[10:10:41.559] Timer tick, asking Buildbucket for the build status
[10:10:41.674] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:41.674] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:91:0) after 1m0s
[10:11:41.706] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:91:0)
[10:11:41.706] Timer tick, asking Buildbucket for the build status
[10:11:41.766] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:41.766] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:92:0) after 1m0s
[10:12:41.803] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:92:0)
[10:12:41.803] Timer tick, asking Buildbucket for the build status
[10:12:42.087] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:42.087] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:93:0) after 1m0s
[10:13:42.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:93:0)
[10:13:42.184] Timer tick, asking Buildbucket for the build status
[10:13:42.247] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:42.247] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:94:0) after 1m0s
[10:14:42.299] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:94:0)
[10:14:42.299] Timer tick, asking Buildbucket for the build status
[10:14:42.425] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:42.425] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:95:0) after 1m0s
[10:15:42.501] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:95:0)
[10:15:42.505] Timer tick, asking Buildbucket for the build status
[10:15:42.736] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:42.736] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:96:0) after 1m0s
[10:16:42.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:96:0)
[10:16:42.804] Timer tick, asking Buildbucket for the build status
[10:16:42.913] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:42.913] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:97:0) after 1m0s
[10:17:43.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:97:0)
[10:17:43.098] Timer tick, asking Buildbucket for the build status
[10:17:43.210] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:43.210] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:98:0) after 1m0s
[10:18:43.394] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:98:0)
[10:18:43.394] Timer tick, asking Buildbucket for the build status
[10:18:43.696] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:43.696] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:99:0) after 1m0s
[10:19:43.758] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:99:0)
[10:19:43.758] Timer tick, asking Buildbucket for the build status
[10:19:43.927] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:43.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:100:0) after 1m0s
[10:20:43.986] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:100:0)
[10:20:43.986] Timer tick, asking Buildbucket for the build status
[10:20:44.055] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:44.055] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:101:0) after 1m0s
[10:21:44.127] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:101:0)
[10:21:44.127] Timer tick, asking Buildbucket for the build status
[10:21:44.212] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:44.212] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:102:0) after 1m0s
[10:22:44.261] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:102:0)
[10:22:44.261] Timer tick, asking Buildbucket for the build status
[10:22:44.373] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:44.373] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:103:0) after 1m0s
[10:23:44.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:103:0)
[10:23:44.508] Timer tick, asking Buildbucket for the build status
[10:23:44.586] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:44.586] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:104:0) after 1m0s
[10:24:44.702] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:104:0)
[10:24:44.702] Timer tick, asking Buildbucket for the build status
[10:24:45.002] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:45.002] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:105:0) after 1m0s
[10:25:45.140] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:105:0)
[10:25:45.140] Timer tick, asking Buildbucket for the build status
[10:25:45.220] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:45.220] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:106:0) after 1m0s
[10:26:45.286] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:106:0)
[10:26:45.286] Timer tick, asking Buildbucket for the build status
[10:26:45.363] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:45.363] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:107:0) after 1m0s
[10:27:45.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:107:0)
[10:27:45.574] Timer tick, asking Buildbucket for the build status
[10:27:45.801] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:45.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:108:0) after 1m0s
[10:28:45.916] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:108:0)
[10:28:45.916] Timer tick, asking Buildbucket for the build status
[10:28:45.980] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:45.980] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:109:0) after 1m0s
[10:29:46.199] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:109:0)
[10:29:46.199] Timer tick, asking Buildbucket for the build status
[10:29:46.273] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:46.273] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:110:0) after 1m0s
[10:30:46.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:110:0)
[10:30:46.342] Timer tick, asking Buildbucket for the build status
[10:30:46.638] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:46.638] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:111:0) after 1m0s
[10:31:46.656] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:111:0)
[10:31:46.656] Timer tick, asking Buildbucket for the build status
[10:31:46.735] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:46.735] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:112:0) after 1m0s
[10:32:46.856] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:112:0)
[10:32:46.856] Timer tick, asking Buildbucket for the build status
[10:32:47.039] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:47.039] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:113:0) after 1m0s
[10:33:47.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:113:0)
[10:33:47.184] Timer tick, asking Buildbucket for the build status
[10:33:47.270] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:47.270] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:114:0) after 1m0s
[10:34:47.408] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:114:0)
[10:34:47.408] Timer tick, asking Buildbucket for the build status
[10:34:49.536] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:49.536] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:115:0) after 1m0s
[10:35:49.621] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:115:0)
[10:35:49.621] Timer tick, asking Buildbucket for the build status
[10:35:49.693] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:49.693] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:116:0) after 1m0s
[10:36:49.805] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:116:0)
[10:36:49.805] Timer tick, asking Buildbucket for the build status
[10:36:49.869] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:49.869] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:117:0) after 1m0s
[10:37:49.927] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:117:0)
[10:37:49.927] Timer tick, asking Buildbucket for the build status
[10:37:50.081] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:50.081] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:118:0) after 1m0s
[10:38:50.153] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:118:0)
[10:38:50.153] Timer tick, asking Buildbucket for the build status
[10:38:50.218] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:50.218] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:119:0) after 1m0s
[10:39:50.270] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:119:0)
[10:39:50.270] Timer tick, asking Buildbucket for the build status
[10:39:50.336] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:50.336] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:120:0) after 1m0s
[10:40:50.517] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:120:0)
[10:40:50.517] Timer tick, asking Buildbucket for the build status
[10:40:50.588] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:50.588] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:121:0) after 1m0s
[10:41:50.699] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:121:0)
[10:41:50.699] Timer tick, asking Buildbucket for the build status
[10:41:50.767] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:50.767] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:122:0) after 1m0s
[10:42:50.789] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:122:0)
[10:42:50.789] Timer tick, asking Buildbucket for the build status
[10:42:50.893] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:50.893] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:123:0) after 1m0s
[10:43:50.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:123:0)
[10:43:50.946] Timer tick, asking Buildbucket for the build status
[10:43:51.167] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:51.168] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:124:0) after 1m0s
[10:44:51.197] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:124:0)
[10:44:51.197] Timer tick, asking Buildbucket for the build status
[10:44:51.318] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:51.318] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:125:0) after 1m0s
[10:45:51.388] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:125:0)
[10:45:51.388] Timer tick, asking Buildbucket for the build status
[10:45:51.683] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:51.683] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:126:0) after 1m0s
[10:46:51.721] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:126:0)
[10:46:51.721] Timer tick, asking Buildbucket for the build status
[10:46:51.786] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:51.786] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:127:0) after 1m0s
[10:47:51.826] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:127:0)
[10:47:51.826] Timer tick, asking Buildbucket for the build status
[10:47:51.931] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:51.931] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:128:0) after 1m0s
[10:48:52.073] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:128:0)
[10:48:52.073] Timer tick, asking Buildbucket for the build status
[10:48:52.248] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:52.248] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:129:0) after 1m0s
[10:49:52.306] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:129:0)
[10:49:52.306] Timer tick, asking Buildbucket for the build status
[10:49:52.453] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:52.453] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:130:0) after 1m0s
[10:50:52.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:130:0)
[10:50:52.561] Timer tick, asking Buildbucket for the build status
[10:50:52.667] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:52.667] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:131:0) after 1m0s
[10:51:52.801] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:131:0)
[10:51:52.801] Timer tick, asking Buildbucket for the build status
[10:51:52.907] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:52.908] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:132:0) after 1m0s
[10:52:53.007] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:132:0)
[10:52:53.007] Timer tick, asking Buildbucket for the build status
[10:52:53.290] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:53.290] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:133:0) after 1m0s
[10:53:53.334] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:133:0)
[10:53:53.334] Timer tick, asking Buildbucket for the build status
[10:53:53.541] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:53.541] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:134:0) after 1m0s
[10:54:53.709] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:134:0)
[10:54:53.709] Timer tick, asking Buildbucket for the build status
[10:54:53.956] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:53.956] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:135:0) after 1m0s
[10:55:54.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:135:0)
[10:55:54.131] Timer tick, asking Buildbucket for the build status
[10:55:54.242] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:54.242] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:136:0) after 1m0s
[10:56:54.423] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:136:0)
[10:56:54.423] Timer tick, asking Buildbucket for the build status
[10:56:54.489] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:54.489] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:137:0) after 1m0s
[10:57:54.570] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:137:0)
[10:57:54.571] Timer tick, asking Buildbucket for the build status
[10:57:54.671] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:54.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:138:0) after 1m0s
[10:58:54.824] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:138:0)
[10:58:54.824] Timer tick, asking Buildbucket for the build status
[10:58:54.927] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:54.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:139:0) after 1m0s
[10:59:55.052] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:139:0)
[10:59:55.052] Timer tick, asking Buildbucket for the build status
[10:59:55.200] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:55.200] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:140:0) after 1m0s
[11:00:55.250] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:140:0)
[11:00:55.250] Timer tick, asking Buildbucket for the build status
[11:00:55.424] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:55.424] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:141:0) after 1m0s
[11:01:55.495] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:141:0)
[11:01:55.495] Timer tick, asking Buildbucket for the build status
[11:01:55.556] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:55.556] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:142:0) after 1m0s
[11:02:55.614] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:142:0)
[11:02:55.614] Timer tick, asking Buildbucket for the build status
[11:02:55.803] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:55.803] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:143:0) after 1m0s
[11:03:55.827] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:143:0)
[11:03:55.827] Timer tick, asking Buildbucket for the build status
[11:03:55.893] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:55.893] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:144:0) after 1m0s
[11:04:55.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:144:0)
[11:04:55.956] Timer tick, asking Buildbucket for the build status
[11:04:56.042] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:56.042] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:145:0) after 1m0s
[11:05:56.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:145:0)
[11:05:56.071] Timer tick, asking Buildbucket for the build status
[11:05:56.126] Build 8868652059141131600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:56.126] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034082121818392736:146:0) after 1m0s
[11:06:16.515] Received PubSub notification, asking Buildbucket for the build status
[11:06:16.632] Build 8868652059141131600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:06:16.632] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1600599975775714",
"created_by": "project:chromium",
"created_ts": "1600591332261852",
"id": "8868652059141131600",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f502f40a89a3e35fcee2048b5a355c47a81280cb\"}}",
"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\": \"f502f40a89a3e35fcee2048b5a355c47a81280cb\", \"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-4-g0d1\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 571, \"got_angle_revision\": \"76e90947a8e421308dff9d00c4955717c6667f12\", \"got_dawn_revision\": \"dcd37fa4f47663d1a68c1c7fb10738b6ea0e0a4c\", \"got_nacl_revision\": \"69a0d6e8affc94187af10e0673592d1b238c6eb0\", \"got_revision\": \"f502f40a89a3e35fcee2048b5a355c47a81280cb\", \"got_revision_cp\": \"refs/heads/master@{#808735}\", \"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\": \"f502f40a89a3e35fcee2048b5a355c47a81280cb\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-4-g0d1\"], \"image\": [\"chrome-win10-1703-20091600-48bae199fe7\"], \"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": "1600591372480210",
"status": "COMPLETED",
"status_changed_ts": "1600599976138491",
"tags": [
"build_address:luci.chromium.ci/win32-official/571",
"builder:win32-official",
"buildset:commit/git/f502f40a89a3e35fcee2048b5a355c47a81280cb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f502f40a89a3e35fcee2048b5a355c47a81280cb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034082121818392736",
"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/8868652059141131600/+/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:4ec37f10cc8e9310",
"user_agent:luci-scheduler"
],
"updated_ts": "1600599976156376",
"url": "https://ci.chromium.org/b/8868652059141131600",
"utcnow_ts": "1600599976623277"
}
}
[11:06:16.632] Invocation finished in 2h24m6.307628138s with status SUCCEEDED