[00:35:54.186] New invocation is queued and will start shortly
[00:35:55.427] Starting the invocation (attempt 1)
[00:35:55.468] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:35:55.468] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:35:55.468] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030670030513957104",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"491d0e51fd13c4a3ea18137956962b26c44e5292\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9030670030513957104",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/491d0e51fd13c4a3ea18137956962b26c44e5292",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/491d0e51fd13c4a3ea18137956962b26c44e5292",
"gitiles_ref:refs/heads/master"
]
}
[00:35:56.989] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603845355860509",
"id": "8865239968091518288",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"491d0e51fd13c4a3ea18137956962b26c44e5292\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603845356704376",
"tags": [
"build_address:luci.chromium.ci/win32-official/827",
"builder:win32-official",
"buildset:commit/git/491d0e51fd13c4a3ea18137956962b26c44e5292",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/491d0e51fd13c4a3ea18137956962b26c44e5292",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030670030513957104",
"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/8865239968091518288/+/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": "1603845356704532",
"url": "https://ci.chromium.org/b/8865239968091518288",
"utcnow_ts": "1603845356971316"
}
}
[00:35:56.989] Task URL: https://ci.chromium.org/b/8865239968091518288
[00:35:56.989] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:2:0) after 1m0s
[00:36:57.096] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:2:0)
[00:36:57.096] Timer tick, asking Buildbucket for the build status
[00:36:57.283] Build 8865239968091518288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:57.283] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:3:0) after 1m0s
[00:37:57.413] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:3:0)
[00:37:57.413] Timer tick, asking Buildbucket for the build status
[00:37:57.507] Build 8865239968091518288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:57.507] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:4:0) after 1m0s
[00:38:57.563] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:4:0)
[00:38:57.563] Timer tick, asking Buildbucket for the build status
[00:38:57.668] Build 8865239968091518288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:57.668] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:5:0) after 1m0s
[00:39:57.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:5:0)
[00:39:57.740] Timer tick, asking Buildbucket for the build status
[00:39:57.896] Build 8865239968091518288: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:57.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:6:0) after 1m0s
[00:40:10.922] Received PubSub notification, asking Buildbucket for the build status
[00:40:11.032] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:57.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:6:0)
[00:40:57.969] Timer tick, asking Buildbucket for the build status
[00:40:58.118] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:58.118] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:8:0) after 1m0s
[00:41:58.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:8:0)
[00:41:58.156] Timer tick, asking Buildbucket for the build status
[00:41:58.231] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:58.231] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:9:0) after 1m0s
[00:42:58.293] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:9:0)
[00:42:58.293] Timer tick, asking Buildbucket for the build status
[00:42:58.377] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:58.377] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:10:0) after 1m0s
[00:43:58.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:10:0)
[00:43:58.450] Timer tick, asking Buildbucket for the build status
[00:43:58.545] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:58.545] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:11:0) after 1m0s
[00:44:58.629] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:11:0)
[00:44:58.629] Timer tick, asking Buildbucket for the build status
[00:44:58.726] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:58.726] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:12:0) after 1m0s
[00:45:58.868] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:12:0)
[00:45:58.868] Timer tick, asking Buildbucket for the build status
[00:45:59.000] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:59.000] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:13:0) after 1m0s
[00:46:59.049] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:13:0)
[00:46:59.049] Timer tick, asking Buildbucket for the build status
[00:46:59.180] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:59.180] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:14:0) after 1m0s
[00:47:59.414] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:14:0)
[00:47:59.414] Timer tick, asking Buildbucket for the build status
[00:47:59.803] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:59.803] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:15:0) after 1m0s
[00:48:59.847] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:15:0)
[00:48:59.847] Timer tick, asking Buildbucket for the build status
[00:49:00.221] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:00.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:16:0) after 1m0s
[00:50:00.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:16:0)
[00:50:00.274] Timer tick, asking Buildbucket for the build status
[00:50:00.349] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:00.349] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:17:0) after 1m0s
[00:51:00.679] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:17:0)
[00:51:00.679] Timer tick, asking Buildbucket for the build status
[00:51:00.854] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:00.854] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:18:0) after 1m0s
[00:52:00.900] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:18:0)
[00:52:00.900] Timer tick, asking Buildbucket for the build status
[00:52:01.096] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:01.096] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:19:0) after 1m0s
[00:53:01.286] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:19:0)
[00:53:01.287] Timer tick, asking Buildbucket for the build status
[00:53:01.512] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:01.512] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:20:0) after 1m0s
[00:54:02.031] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:20:0)
[00:54:02.031] Timer tick, asking Buildbucket for the build status
[00:54:02.118] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:02.118] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:21:0) after 1m0s
[00:55:02.202] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:21:0)
[00:55:02.202] Timer tick, asking Buildbucket for the build status
[00:55:02.287] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:02.287] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:22:0) after 1m0s
[00:56:02.815] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:22:0)
[00:56:02.815] Timer tick, asking Buildbucket for the build status
[00:56:02.964] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:02.964] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:23:0) after 1m0s
[00:57:03.079] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:23:0)
[00:57:03.079] Timer tick, asking Buildbucket for the build status
[00:57:03.171] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:03.171] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:24:0) after 1m0s
[00:58:03.245] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:24:0)
[00:58:03.245] Timer tick, asking Buildbucket for the build status
[00:58:03.425] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:03.425] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:25:0) after 1m0s
[00:59:03.458] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:25:0)
[00:59:03.458] Timer tick, asking Buildbucket for the build status
[00:59:03.638] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:03.638] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:26:0) after 1m0s
[01:00:03.636] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:26:0)
[01:00:03.636] Timer tick, asking Buildbucket for the build status
[01:00:04.020] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:04.020] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:27:0) after 1m0s
[01:01:04.163] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:27:0)
[01:01:04.163] Timer tick, asking Buildbucket for the build status
[01:01:06.725] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:06.725] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:28:0) after 1m0s
[01:02:06.933] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:28:0)
[01:02:06.933] Timer tick, asking Buildbucket for the build status
[01:02:07.003] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:07.003] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:29:0) after 1m0s
[01:03:07.080] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:29:0)
[01:03:07.080] Timer tick, asking Buildbucket for the build status
[01:03:07.205] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:07.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:30:0) after 1m0s
[01:04:07.416] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:30:0)
[01:04:07.416] Timer tick, asking Buildbucket for the build status
[01:04:07.480] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:07.480] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:31:0) after 1m0s
[01:05:07.621] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:31:0)
[01:05:07.621] Timer tick, asking Buildbucket for the build status
[01:05:07.685] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:07.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:32:0) after 1m0s
[01:06:07.754] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:32:0)
[01:06:07.754] Timer tick, asking Buildbucket for the build status
[01:06:07.946] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:07.946] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:33:0) after 1m0s
[01:07:07.997] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:33:0)
[01:07:07.997] Timer tick, asking Buildbucket for the build status
[01:07:08.107] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:08.107] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:34:0) after 1m0s
[01:08:08.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:34:0)
[01:08:08.131] Timer tick, asking Buildbucket for the build status
[01:08:08.793] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:08.793] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:35:0) after 1m0s
[01:09:08.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:35:0)
[01:09:08.871] Timer tick, asking Buildbucket for the build status
[01:09:08.988] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:08.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:36:0) after 1m0s
[01:10:09.093] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:36:0)
[01:10:09.093] Timer tick, asking Buildbucket for the build status
[01:10:09.287] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:09.287] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:37:0) after 1m0s
[01:11:09.340] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:37:0)
[01:11:09.340] Timer tick, asking Buildbucket for the build status
[01:11:09.850] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:09.850] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:38:0) after 1m0s
[01:12:09.949] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:38:0)
[01:12:09.949] Timer tick, asking Buildbucket for the build status
[01:12:10.502] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:10.502] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:39:0) after 1m0s
[01:13:10.530] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:39:0)
[01:13:10.530] Timer tick, asking Buildbucket for the build status
[01:13:10.609] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:10.609] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:40:0) after 1m0s
[01:14:10.633] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:40:0)
[01:14:10.633] Timer tick, asking Buildbucket for the build status
[01:14:10.699] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:10.699] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:41:0) after 1m0s
[01:15:10.723] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:41:0)
[01:15:10.723] Timer tick, asking Buildbucket for the build status
[01:15:10.789] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:10.789] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:42:0) after 1m0s
[01:16:10.829] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:42:0)
[01:16:10.829] Timer tick, asking Buildbucket for the build status
[01:16:10.951] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:10.951] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:43:0) after 1m0s
[01:17:11.034] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:43:0)
[01:17:11.034] Timer tick, asking Buildbucket for the build status
[01:17:11.307] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:11.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:44:0) after 1m0s
[01:18:11.340] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:44:0)
[01:18:11.340] Timer tick, asking Buildbucket for the build status
[01:18:11.502] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:11.502] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:45:0) after 1m0s
[01:19:11.511] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:45:0)
[01:19:11.511] Timer tick, asking Buildbucket for the build status
[01:19:11.792] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:11.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:46:0) after 1m0s
[01:20:11.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:46:0)
[01:20:11.852] Timer tick, asking Buildbucket for the build status
[01:20:11.951] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:11.951] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:47:0) after 1m0s
[01:21:11.978] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:47:0)
[01:21:11.978] Timer tick, asking Buildbucket for the build status
[01:21:12.196] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:12.196] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:48:0) after 1m0s
[01:22:12.192] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:48:0)
[01:22:12.192] Timer tick, asking Buildbucket for the build status
[01:22:12.322] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:12.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:49:0) after 1m0s
[01:23:12.394] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:49:0)
[01:23:12.394] Timer tick, asking Buildbucket for the build status
[01:23:12.498] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:12.498] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:50:0) after 1m0s
[01:24:12.549] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:50:0)
[01:24:12.550] Timer tick, asking Buildbucket for the build status
[01:24:12.800] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:12.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:51:0) after 1m0s
[01:25:12.848] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:51:0)
[01:25:12.848] Timer tick, asking Buildbucket for the build status
[01:25:12.931] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:12.931] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:52:0) after 1m0s
[01:26:12.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:52:0)
[01:26:12.953] Timer tick, asking Buildbucket for the build status
[01:26:13.092] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:13.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:53:0) after 1m0s
[01:27:13.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:53:0)
[01:27:13.175] Timer tick, asking Buildbucket for the build status
[01:27:15.985] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:15.985] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:54:0) after 1m0s
[01:28:16.026] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:54:0)
[01:28:16.026] Timer tick, asking Buildbucket for the build status
[01:28:16.114] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:16.114] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:55:0) after 1m0s
[01:29:16.202] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:55:0)
[01:29:16.202] Timer tick, asking Buildbucket for the build status
[01:29:16.329] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:16.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:56:0) after 1m0s
[01:30:16.356] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:56:0)
[01:30:16.356] Timer tick, asking Buildbucket for the build status
[01:30:16.649] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:16.649] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:57:0) after 1m0s
[01:31:16.662] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:57:0)
[01:31:16.662] Timer tick, asking Buildbucket for the build status
[01:31:16.862] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:16.862] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:58:0) after 1m0s
[01:32:16.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:58:0)
[01:32:16.925] Timer tick, asking Buildbucket for the build status
[01:32:16.998] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:16.998] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:59:0) after 1m0s
[01:33:17.026] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:59:0)
[01:33:17.026] Timer tick, asking Buildbucket for the build status
[01:33:17.249] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:17.249] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:60:0) after 1m0s
[01:34:17.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:60:0)
[01:34:17.323] Timer tick, asking Buildbucket for the build status
[01:34:17.410] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:17.410] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:61:0) after 1m0s
[01:35:17.462] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:61:0)
[01:35:17.462] Timer tick, asking Buildbucket for the build status
[01:35:18.111] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:18.111] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:62:0) after 1m0s
[01:36:18.143] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:62:0)
[01:36:18.143] Timer tick, asking Buildbucket for the build status
[01:36:18.376] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:18.376] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:63:0) after 1m0s
[01:37:18.426] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:63:0)
[01:37:18.426] Timer tick, asking Buildbucket for the build status
[01:37:18.530] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:18.530] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:64:0) after 1m0s
[01:38:18.538] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:64:0)
[01:38:18.538] Timer tick, asking Buildbucket for the build status
[01:38:18.633] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:18.634] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:65:0) after 1m0s
[01:39:18.663] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:65:0)
[01:39:18.663] Timer tick, asking Buildbucket for the build status
[01:39:18.780] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:18.780] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:66:0) after 1m0s
[01:40:18.844] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:66:0)
[01:40:18.844] Timer tick, asking Buildbucket for the build status
[01:40:19.022] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:19.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:67:0) after 1m0s
[01:41:19.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:67:0)
[01:41:19.095] Timer tick, asking Buildbucket for the build status
[01:41:19.597] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:19.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:68:0) after 1m0s
[01:42:19.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:68:0)
[01:42:19.627] Timer tick, asking Buildbucket for the build status
[01:42:19.712] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:19.712] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:69:0) after 1m0s
[01:43:19.719] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:69:0)
[01:43:19.719] Timer tick, asking Buildbucket for the build status
[01:43:19.801] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:19.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:70:0) after 1m0s
[01:44:19.894] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:70:0)
[01:44:19.894] Timer tick, asking Buildbucket for the build status
[01:44:19.959] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:19.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:71:0) after 1m0s
[01:45:20.086] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:71:0)
[01:45:20.086] Timer tick, asking Buildbucket for the build status
[01:45:20.173] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:20.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:72:0) after 1m0s
[01:46:20.249] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:72:0)
[01:46:20.249] Timer tick, asking Buildbucket for the build status
[01:46:20.374] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:20.374] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:73:0) after 1m0s
[01:47:20.727] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:73:0)
[01:47:20.727] Timer tick, asking Buildbucket for the build status
[01:47:20.810] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:20.810] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:74:0) after 1m0s
[01:48:21.038] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:74:0)
[01:48:21.038] Timer tick, asking Buildbucket for the build status
[01:48:21.165] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:21.165] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:75:0) after 1m0s
[01:49:21.258] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:75:0)
[01:49:21.258] Timer tick, asking Buildbucket for the build status
[01:49:21.475] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:21.475] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:76:0) after 1m0s
[01:50:21.569] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:76:0)
[01:50:21.569] Timer tick, asking Buildbucket for the build status
[01:50:21.644] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:21.644] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:77:0) after 1m0s
[01:51:21.759] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:77:0)
[01:51:21.759] Timer tick, asking Buildbucket for the build status
[01:51:21.896] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:21.896] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:78:0) after 1m0s
[01:52:21.974] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:78:0)
[01:52:21.974] Timer tick, asking Buildbucket for the build status
[01:52:22.185] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:22.185] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:79:0) after 1m0s
[01:53:22.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:79:0)
[01:53:22.281] Timer tick, asking Buildbucket for the build status
[01:53:22.382] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:22.382] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:80:0) after 1m0s
[01:54:22.376] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:80:0)
[01:54:22.376] Timer tick, asking Buildbucket for the build status
[01:54:22.453] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:22.453] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:81:0) after 1m0s
[01:55:22.601] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:81:0)
[01:55:22.601] Timer tick, asking Buildbucket for the build status
[01:55:22.662] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:22.662] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:82:0) after 1m0s
[01:56:22.724] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:82:0)
[01:56:22.724] Timer tick, asking Buildbucket for the build status
[01:56:22.835] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:22.835] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:83:0) after 1m0s
[01:57:22.883] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:83:0)
[01:57:22.883] Timer tick, asking Buildbucket for the build status
[01:57:22.995] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:22.995] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:84:0) after 1m0s
[01:58:23.093] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:84:0)
[01:58:23.093] Timer tick, asking Buildbucket for the build status
[01:58:23.176] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:23.176] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:85:0) after 1m0s
[01:59:23.240] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:85:0)
[01:59:23.240] Timer tick, asking Buildbucket for the build status
[01:59:23.306] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:23.306] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:86:0) after 1m0s
[02:00:23.410] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:86:0)
[02:00:23.410] Timer tick, asking Buildbucket for the build status
[02:00:23.662] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:23.662] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:87:0) after 1m0s
[02:01:23.719] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:87:0)
[02:01:23.719] Timer tick, asking Buildbucket for the build status
[02:01:23.877] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:23.877] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:88:0) after 1m0s
[02:02:23.982] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:88:0)
[02:02:23.982] Timer tick, asking Buildbucket for the build status
[02:02:24.085] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:24.085] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:89:0) after 1m0s
[02:03:24.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:89:0)
[02:03:24.175] Timer tick, asking Buildbucket for the build status
[02:03:24.588] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:24.588] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:90:0) after 1m0s
[02:04:24.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:90:0)
[02:04:24.622] Timer tick, asking Buildbucket for the build status
[02:04:24.685] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:24.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:91:0) after 1m0s
[02:05:24.691] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:91:0)
[02:05:24.691] Timer tick, asking Buildbucket for the build status
[02:05:24.760] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:24.760] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:92:0) after 1m0s
[02:06:24.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:92:0)
[02:06:24.773] Timer tick, asking Buildbucket for the build status
[02:06:24.926] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:24.926] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:93:0) after 1m0s
[02:07:25.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:93:0)
[02:07:25.071] Timer tick, asking Buildbucket for the build status
[02:07:25.146] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:25.146] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:94:0) after 1m0s
[02:08:25.162] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:94:0)
[02:08:25.162] Timer tick, asking Buildbucket for the build status
[02:08:25.238] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:25.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:95:0) after 1m0s
[02:09:25.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:95:0)
[02:09:25.278] Timer tick, asking Buildbucket for the build status
[02:09:25.348] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:25.348] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:96:0) after 1m0s
[02:10:25.535] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:96:0)
[02:10:25.535] Timer tick, asking Buildbucket for the build status
[02:10:25.910] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:25.910] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:97:0) after 1m0s
[02:11:25.927] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:97:0)
[02:11:25.927] Timer tick, asking Buildbucket for the build status
[02:11:26.330] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:26.330] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:98:0) after 1m0s
[02:12:26.351] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:98:0)
[02:12:26.351] Timer tick, asking Buildbucket for the build status
[02:12:26.676] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:26.676] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:99:0) after 1m0s
[02:13:26.712] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:99:0)
[02:13:26.712] Timer tick, asking Buildbucket for the build status
[02:13:26.882] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:26.882] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:100:0) after 1m0s
[02:14:27.021] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:100:0)
[02:14:27.021] Timer tick, asking Buildbucket for the build status
[02:14:27.092] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:27.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:101:0) after 1m0s
[02:15:27.134] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:101:0)
[02:15:27.134] Timer tick, asking Buildbucket for the build status
[02:15:27.203] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:27.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:102:0) after 1m0s
[02:16:27.209] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:102:0)
[02:16:27.209] Timer tick, asking Buildbucket for the build status
[02:16:27.355] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:27.355] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:103:0) after 1m0s
[02:17:27.459] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:103:0)
[02:17:27.460] Timer tick, asking Buildbucket for the build status
[02:17:27.518] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:27.518] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:104:0) after 1m0s
[02:18:27.571] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:104:0)
[02:18:27.571] Timer tick, asking Buildbucket for the build status
[02:18:27.632] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:27.633] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:105:0) after 1m0s
[02:19:27.691] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:105:0)
[02:19:27.691] Timer tick, asking Buildbucket for the build status
[02:19:27.750] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:27.750] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:106:0) after 1m0s
[02:20:27.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:106:0)
[02:20:27.772] Timer tick, asking Buildbucket for the build status
[02:20:27.839] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:27.839] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:107:0) after 1m0s
[02:21:27.961] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:107:0)
[02:21:27.961] Timer tick, asking Buildbucket for the build status
[02:21:28.074] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:28.074] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:108:0) after 1m0s
[02:22:28.112] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:108:0)
[02:22:28.112] Timer tick, asking Buildbucket for the build status
[02:22:28.176] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:28.176] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:109:0) after 1m0s
[02:23:28.255] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:109:0)
[02:23:28.255] Timer tick, asking Buildbucket for the build status
[02:23:28.514] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:28.516] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:110:0) after 1m0s
[02:24:28.641] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:110:0)
[02:24:28.641] Timer tick, asking Buildbucket for the build status
[02:24:28.729] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:28.729] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:111:0) after 1m0s
[02:25:28.779] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:111:0)
[02:25:28.779] Timer tick, asking Buildbucket for the build status
[02:25:28.838] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:28.838] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:112:0) after 1m0s
[02:26:28.978] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:112:0)
[02:26:28.978] Timer tick, asking Buildbucket for the build status
[02:26:29.086] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:29.086] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:113:0) after 1m0s
[02:27:29.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:113:0)
[02:27:29.146] Timer tick, asking Buildbucket for the build status
[02:27:29.211] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:29.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:114:0) after 1m0s
[02:28:29.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:114:0)
[02:28:29.348] Timer tick, asking Buildbucket for the build status
[02:28:31.847] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:31.847] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:115:0) after 1m0s
[02:29:31.906] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:115:0)
[02:29:31.906] Timer tick, asking Buildbucket for the build status
[02:29:32.169] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:32.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:116:0) after 1m0s
[02:30:32.236] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:116:0)
[02:30:32.236] Timer tick, asking Buildbucket for the build status
[02:30:32.572] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:32.572] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:117:0) after 1m0s
[02:31:32.605] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:117:0)
[02:31:32.605] Timer tick, asking Buildbucket for the build status
[02:31:32.714] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:32.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:118:0) after 1m0s
[02:32:32.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:118:0)
[02:32:32.740] Timer tick, asking Buildbucket for the build status
[02:32:32.937] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:32.937] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:119:0) after 1m0s
[02:33:32.990] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:119:0)
[02:33:32.990] Timer tick, asking Buildbucket for the build status
[02:33:33.058] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:33.058] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:120:0) after 1m0s
[02:34:33.124] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:120:0)
[02:34:33.124] Timer tick, asking Buildbucket for the build status
[02:34:33.498] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:33.498] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:121:0) after 1m0s
[02:35:33.495] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:121:0)
[02:35:33.495] Timer tick, asking Buildbucket for the build status
[02:35:33.602] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:33.602] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:122:0) after 1m0s
[02:36:33.630] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:122:0)
[02:36:33.630] Timer tick, asking Buildbucket for the build status
[02:36:33.759] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:33.759] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:123:0) after 1m0s
[02:37:33.783] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:123:0)
[02:37:33.783] Timer tick, asking Buildbucket for the build status
[02:37:33.985] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:33.985] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:124:0) after 1m0s
[02:38:34.116] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:124:0)
[02:38:34.116] Timer tick, asking Buildbucket for the build status
[02:38:34.192] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:34.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:125:0) after 1m0s
[02:39:34.355] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:125:0)
[02:39:34.355] Timer tick, asking Buildbucket for the build status
[02:39:34.481] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:34.481] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:126:0) after 1m0s
[02:40:34.500] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:126:0)
[02:40:34.500] Timer tick, asking Buildbucket for the build status
[02:40:34.671] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:34.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:127:0) after 1m0s
[02:41:34.788] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:127:0)
[02:41:34.788] Timer tick, asking Buildbucket for the build status
[02:41:34.906] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:34.906] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:128:0) after 1m0s
[02:42:34.996] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:128:0)
[02:42:34.996] Timer tick, asking Buildbucket for the build status
[02:42:35.189] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:35.189] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:129:0) after 1m0s
[02:43:35.267] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:129:0)
[02:43:35.267] Timer tick, asking Buildbucket for the build status
[02:43:35.340] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:35.340] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:130:0) after 1m0s
[02:44:35.402] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:130:0)
[02:44:35.402] Timer tick, asking Buildbucket for the build status
[02:44:35.494] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:35.494] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:131:0) after 1m0s
[02:45:35.566] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:131:0)
[02:45:35.566] Timer tick, asking Buildbucket for the build status
[02:45:35.638] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:35.638] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:132:0) after 1m0s
[02:46:35.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:132:0)
[02:46:35.624] Timer tick, asking Buildbucket for the build status
[02:46:35.719] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:35.719] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:133:0) after 1m0s
[02:47:35.731] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:133:0)
[02:47:35.731] Timer tick, asking Buildbucket for the build status
[02:47:35.822] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:35.822] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:134:0) after 1m0s
[02:48:35.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:134:0)
[02:48:35.852] Timer tick, asking Buildbucket for the build status
[02:48:36.427] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:36.427] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:135:0) after 1m0s
[02:49:36.505] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:135:0)
[02:49:36.505] Timer tick, asking Buildbucket for the build status
[02:49:36.565] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:36.565] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:136:0) after 1m0s
[02:50:36.634] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:136:0)
[02:50:36.634] Timer tick, asking Buildbucket for the build status
[02:50:36.742] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:36.742] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:137:0) after 1m0s
[02:51:36.816] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:137:0)
[02:51:36.816] Timer tick, asking Buildbucket for the build status
[02:51:36.935] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:36.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:138:0) after 1m0s
[02:52:36.955] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:138:0)
[02:52:36.955] Timer tick, asking Buildbucket for the build status
[02:52:37.190] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:37.190] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:139:0) after 1m0s
[02:53:37.247] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:139:0)
[02:53:37.247] Timer tick, asking Buildbucket for the build status
[02:53:37.389] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:37.389] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:140:0) after 1m0s
[02:54:37.474] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:140:0)
[02:54:37.474] Timer tick, asking Buildbucket for the build status
[02:54:37.535] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:37.535] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:141:0) after 1m0s
[02:55:37.596] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:141:0)
[02:55:37.596] Timer tick, asking Buildbucket for the build status
[02:55:37.655] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:37.655] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:142:0) after 1m0s
[02:56:37.651] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:142:0)
[02:56:37.651] Timer tick, asking Buildbucket for the build status
[02:56:37.764] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:37.764] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:143:0) after 1m0s
[02:57:37.802] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:143:0)
[02:57:37.802] Timer tick, asking Buildbucket for the build status
[02:57:37.858] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:37.858] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:144:0) after 1m0s
[02:58:37.954] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:144:0)
[02:58:37.954] Timer tick, asking Buildbucket for the build status
[02:58:38.105] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:38.105] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:145:0) after 1m0s
[02:59:38.128] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:145:0)
[02:59:38.128] Timer tick, asking Buildbucket for the build status
[02:59:38.197] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:38.197] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:146:0) after 1m0s
[03:00:38.267] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:146:0)
[03:00:38.267] Timer tick, asking Buildbucket for the build status
[03:00:38.350] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:38.350] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:147:0) after 1m0s
[03:01:38.670] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:147:0)
[03:01:38.670] Timer tick, asking Buildbucket for the build status
[03:01:38.801] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:38.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:148:0) after 1m0s
[03:02:38.950] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:148:0)
[03:02:38.951] Timer tick, asking Buildbucket for the build status
[03:02:39.033] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:39.033] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:149:0) after 1m0s
[03:03:39.130] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:149:0)
[03:03:39.130] Timer tick, asking Buildbucket for the build status
[03:03:39.237] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:39.237] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:150:0) after 1m0s
[03:04:39.302] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:150:0)
[03:04:39.302] Timer tick, asking Buildbucket for the build status
[03:04:39.378] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:39.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:151:0) after 1m0s
[03:05:39.546] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:151:0)
[03:05:39.546] Timer tick, asking Buildbucket for the build status
[03:05:39.645] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:39.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:152:0) after 1m0s
[03:06:39.862] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:152:0)
[03:06:39.862] Timer tick, asking Buildbucket for the build status
[03:06:40.169] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:40.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:153:0) after 1m0s
[03:07:40.375] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:153:0)
[03:07:40.375] Timer tick, asking Buildbucket for the build status
[03:07:40.459] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:40.459] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:154:0) after 1m0s
[03:08:40.556] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:154:0)
[03:08:40.556] Timer tick, asking Buildbucket for the build status
[03:08:40.626] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:40.626] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:155:0) after 1m0s
[03:09:40.727] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:155:0)
[03:09:40.727] Timer tick, asking Buildbucket for the build status
[03:09:40.797] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:40.797] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:156:0) after 1m0s
[03:10:40.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:156:0)
[03:10:40.899] Timer tick, asking Buildbucket for the build status
[03:10:41.049] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:41.049] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:157:0) after 1m0s
[03:11:41.245] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:157:0)
[03:11:41.245] Timer tick, asking Buildbucket for the build status
[03:11:41.388] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:41.388] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:158:0) after 1m0s
[03:12:41.535] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:158:0)
[03:12:41.535] Timer tick, asking Buildbucket for the build status
[03:12:41.638] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:41.638] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:159:0) after 1m0s
[03:13:41.762] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:159:0)
[03:13:41.762] Timer tick, asking Buildbucket for the build status
[03:13:41.904] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:41.904] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:160:0) after 1m0s
[03:14:42.126] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:160:0)
[03:14:42.126] Timer tick, asking Buildbucket for the build status
[03:14:42.196] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:42.196] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:161:0) after 1m0s
[03:15:42.259] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:161:0)
[03:15:42.259] Timer tick, asking Buildbucket for the build status
[03:15:42.335] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:42.335] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:162:0) after 1m0s
[03:16:42.372] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:162:0)
[03:16:42.372] Timer tick, asking Buildbucket for the build status
[03:16:42.707] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:42.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:163:0) after 1m0s
[03:17:42.752] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:163:0)
[03:17:42.752] Timer tick, asking Buildbucket for the build status
[03:17:42.823] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:42.823] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:164:0) after 1m0s
[03:18:42.891] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:164:0)
[03:18:42.891] Timer tick, asking Buildbucket for the build status
[03:18:43.200] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:43.200] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:165:0) after 1m0s
[03:19:43.274] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:165:0)
[03:19:43.274] Timer tick, asking Buildbucket for the build status
[03:19:43.357] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:43.358] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:166:0) after 1m0s
[03:20:43.410] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:166:0)
[03:20:43.410] Timer tick, asking Buildbucket for the build status
[03:20:43.517] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:43.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:167:0) after 1m0s
[03:21:43.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:167:0)
[03:21:43.574] Timer tick, asking Buildbucket for the build status
[03:21:43.906] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:43.906] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:168:0) after 1m0s
[03:22:43.908] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:168:0)
[03:22:43.908] Timer tick, asking Buildbucket for the build status
[03:22:43.983] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:43.983] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:169:0) after 1m0s
[03:23:44.081] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:169:0)
[03:23:44.081] Timer tick, asking Buildbucket for the build status
[03:23:44.143] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:44.143] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:170:0) after 1m0s
[03:24:44.228] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:170:0)
[03:24:44.229] Timer tick, asking Buildbucket for the build status
[03:24:44.730] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:44.730] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:171:0) after 1m0s
[03:25:44.781] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:171:0)
[03:25:44.781] Timer tick, asking Buildbucket for the build status
[03:25:44.912] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:44.912] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:172:0) after 1m0s
[03:26:45.113] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:172:0)
[03:26:45.113] Timer tick, asking Buildbucket for the build status
[03:26:45.260] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:45.260] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:173:0) after 1m0s
[03:27:45.605] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:173:0)
[03:27:45.605] Timer tick, asking Buildbucket for the build status
[03:27:45.738] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:45.738] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:174:0) after 1m0s
[03:28:45.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:174:0)
[03:28:45.899] Timer tick, asking Buildbucket for the build status
[03:28:46.309] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:46.309] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:175:0) after 1m0s
[03:29:46.340] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:175:0)
[03:29:46.340] Timer tick, asking Buildbucket for the build status
[03:29:46.601] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:46.601] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:176:0) after 1m0s
[03:30:46.644] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:176:0)
[03:30:46.645] Timer tick, asking Buildbucket for the build status
[03:30:46.748] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:46.748] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:177:0) after 1m0s
[03:31:46.787] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:177:0)
[03:31:46.787] Timer tick, asking Buildbucket for the build status
[03:31:46.912] Build 8865239968091518288: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:46.912] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9030670030513957104:178:0) after 1m0s
[03:32:29.262] Received PubSub notification, asking Buildbucket for the build status
[03:32:29.365] Build 8865239968091518288: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:32:29.365] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603855947570521",
"created_by": "project:chromium",
"created_ts": "1603845355860509",
"id": "8865239968091518288",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"491d0e51fd13c4a3ea18137956962b26c44e5292\"}}",
"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\": \"491d0e51fd13c4a3ea18137956962b26c44e5292\", \"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-9-jqa4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 827, \"got_angle_revision\": \"b5ba693316fdd5e680f2bb4cf1a2f9bed589d8e2\", \"got_dawn_revision\": \"6b087819dd1ea56a50e15bcc34c97ad2fa01d463\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"491d0e51fd13c4a3ea18137956962b26c44e5292\", \"got_revision_cp\": \"refs/heads/master@{#821524}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"20aa521f10950ef2db0f60fc487e68d9fa14d703\", \"got_v8_revision_cp\": \"refs/heads/8.8.190@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"491d0e51fd13c4a3ea18137956962b26c44e5292\"}, \"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-9-jqa4\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5613-2fddb4d\"], \"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": "1603845609862183",
"status": "COMPLETED",
"status_changed_ts": "1603855948017632",
"tags": [
"build_address:luci.chromium.ci/win32-official/827",
"builder:win32-official",
"buildset:commit/git/491d0e51fd13c4a3ea18137956962b26c44e5292",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/491d0e51fd13c4a3ea18137956962b26c44e5292",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030670030513957104",
"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/8865239968091518288/+/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:4f857385f28af410",
"user_agent:luci-scheduler"
],
"updated_ts": "1603855948024875",
"url": "https://ci.chromium.org/b/8865239968091518288",
"utcnow_ts": "1603855949354706"
}
}
[03:32:29.365] Invocation finished in 2h56m35.2006372s with status SUCCEEDED