[18:43:44.896] New invocation is queued and will start shortly
[18:43:46.056] Starting the invocation (attempt 1)
[18:43:46.103] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:43:46.104] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:43:46.104] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027158904487944496",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0241bf61c124f5791745218ea98c7122c114e00d\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9027158904487944496",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/0241bf61c124f5791745218ea98c7122c114e00d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0241bf61c124f5791745218ea98c7122c114e00d",
"gitiles_ref:refs/heads/master"
]
}
[18:43:46.790] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607193826199875",
"id": "8861728842457401984",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0241bf61c124f5791745218ea98c7122c114e00d\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607193826624007",
"tags": [
"build_address:luci.chromium.ci/win32-official/1058",
"builder:win32-official",
"buildset:commit/git/0241bf61c124f5791745218ea98c7122c114e00d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0241bf61c124f5791745218ea98c7122c114e00d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027158904487944496",
"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/8861728842457401984/+/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": "1607193826624112",
"url": "https://ci.chromium.org/b/8861728842457401984",
"utcnow_ts": "1607193826780399"
}
}
[18:43:46.790] Task URL: https://ci.chromium.org/b/8861728842457401984
[18:43:46.790] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:2:0) after 1m0s
[18:44:46.872] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:2:0)
[18:44:46.872] Timer tick, asking Buildbucket for the build status
[18:44:46.976] Build 8861728842457401984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:44:46.976] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:3:0) after 1m0s
[18:45:47.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:3:0)
[18:45:47.064] Timer tick, asking Buildbucket for the build status
[18:45:47.152] Build 8861728842457401984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:45:47.152] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:4:0) after 1m0s
[18:46:47.261] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:4:0)
[18:46:47.261] Timer tick, asking Buildbucket for the build status
[18:46:47.755] Build 8861728842457401984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:46:47.755] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:5:0) after 1m0s
[18:47:47.840] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:5:0)
[18:47:47.840] Timer tick, asking Buildbucket for the build status
[18:47:48.168] Build 8861728842457401984: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[18:47:48.168] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:6:0) after 1m0s
[18:48:19.387] Received PubSub notification, asking Buildbucket for the build status
[18:48:19.492] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:48.187] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:6:0)
[18:48:48.187] Timer tick, asking Buildbucket for the build status
[18:48:49.489] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:49.489] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:8:0) after 1m0s
[18:49:49.468] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:8:0)
[18:49:49.468] Timer tick, asking Buildbucket for the build status
[18:49:49.615] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:49.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:9:0) after 1m0s
[18:50:49.617] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:9:0)
[18:50:49.617] Timer tick, asking Buildbucket for the build status
[18:50:50.139] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:50.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:10:0) after 1m0s
[18:51:50.249] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:10:0)
[18:51:50.249] Timer tick, asking Buildbucket for the build status
[18:52:01.468] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:01.468] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:11:0) after 1m0s
[18:53:01.464] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:11:0)
[18:53:01.464] Timer tick, asking Buildbucket for the build status
[18:53:01.939] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:01.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:12:0) after 1m0s
[18:54:01.954] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:12:0)
[18:54:01.954] Timer tick, asking Buildbucket for the build status
[18:54:02.998] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:02.998] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:13:0) after 1m0s
[18:55:03.014] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:13:0)
[18:55:03.014] Timer tick, asking Buildbucket for the build status
[18:55:03.422] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:03.422] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:14:0) after 1m0s
[18:56:03.495] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:14:0)
[18:56:03.495] Timer tick, asking Buildbucket for the build status
[18:56:03.714] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:03.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:15:0) after 1m0s
[18:57:03.775] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:15:0)
[18:57:03.775] Timer tick, asking Buildbucket for the build status
[18:57:04.107] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:04.107] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:16:0) after 1m0s
[18:58:04.170] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:16:0)
[18:58:04.170] Timer tick, asking Buildbucket for the build status
[18:58:04.314] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:04.314] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:17:0) after 1m0s
[18:59:04.583] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:17:0)
[18:59:04.583] Timer tick, asking Buildbucket for the build status
[18:59:04.798] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:04.798] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:18:0) after 1m0s
[19:00:04.970] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:18:0)
[19:00:04.970] Timer tick, asking Buildbucket for the build status
[19:00:06.188] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:06.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:19:0) after 1m0s
[19:01:06.197] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:19:0)
[19:01:06.197] Timer tick, asking Buildbucket for the build status
[19:01:07.211] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:07.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:20:0) after 1m0s
[19:02:07.231] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:20:0)
[19:02:07.231] Timer tick, asking Buildbucket for the build status
[19:02:07.284] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:07.284] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:21:0) after 1m0s
[19:03:07.263] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:21:0)
[19:03:07.263] Timer tick, asking Buildbucket for the build status
[19:03:07.563] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:07.563] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:22:0) after 1m0s
[19:04:07.658] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:22:0)
[19:04:07.658] Timer tick, asking Buildbucket for the build status
[19:04:07.744] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:07.744] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:23:0) after 1m0s
[19:05:07.827] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:23:0)
[19:05:07.827] Timer tick, asking Buildbucket for the build status
[19:05:08.186] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:08.186] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:24:0) after 1m0s
[19:06:08.221] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:24:0)
[19:06:08.221] Timer tick, asking Buildbucket for the build status
[19:06:08.304] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:08.304] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:25:0) after 1m0s
[19:07:08.398] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:25:0)
[19:07:08.398] Timer tick, asking Buildbucket for the build status
[19:07:11.103] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:11.103] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:26:0) after 1m0s
[19:08:11.157] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:26:0)
[19:08:11.157] Timer tick, asking Buildbucket for the build status
[19:08:11.273] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:11.273] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:27:0) after 1m0s
[19:09:11.298] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:27:0)
[19:09:11.298] Timer tick, asking Buildbucket for the build status
[19:09:11.414] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:11.414] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:28:0) after 1m0s
[19:10:11.474] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:28:0)
[19:10:11.474] Timer tick, asking Buildbucket for the build status
[19:10:12.582] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:12.582] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:29:0) after 1m0s
[19:11:12.569] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:29:0)
[19:11:12.569] Timer tick, asking Buildbucket for the build status
[19:11:13.028] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:13.028] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:30:0) after 1m0s
[19:12:13.065] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:30:0)
[19:12:13.065] Timer tick, asking Buildbucket for the build status
[19:12:13.185] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:13.185] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:31:0) after 1m0s
[19:13:13.194] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:31:0)
[19:13:13.194] Timer tick, asking Buildbucket for the build status
[19:13:13.997] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:13.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:32:0) after 1m0s
[19:14:14.107] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:32:0)
[19:14:14.107] Timer tick, asking Buildbucket for the build status
[19:14:14.241] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:14.241] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:33:0) after 1m0s
[19:15:14.296] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:33:0)
[19:15:14.296] Timer tick, asking Buildbucket for the build status
[19:15:14.520] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:14.520] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:34:0) after 1m0s
[19:16:14.576] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:34:0)
[19:16:14.576] Timer tick, asking Buildbucket for the build status
[19:16:14.777] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:14.777] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:35:0) after 1m0s
[19:17:14.789] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:35:0)
[19:17:14.789] Timer tick, asking Buildbucket for the build status
[19:17:15.084] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:15.084] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:36:0) after 1m0s
[19:18:15.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:36:0)
[19:18:15.173] Timer tick, asking Buildbucket for the build status
[19:18:15.484] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:15.484] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:37:0) after 1m0s
[19:19:15.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:37:0)
[19:19:15.508] Timer tick, asking Buildbucket for the build status
[19:19:17.037] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:17.037] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:38:0) after 1m0s
[19:20:17.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:38:0)
[19:20:17.069] Timer tick, asking Buildbucket for the build status
[19:20:18.167] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:18.167] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:39:0) after 1m0s
[19:21:18.139] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:39:0)
[19:21:18.139] Timer tick, asking Buildbucket for the build status
[19:21:22.701] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:22.701] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:40:0) after 1m0s
[19:22:22.726] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:40:0)
[19:22:22.726] Timer tick, asking Buildbucket for the build status
[19:22:22.926] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:22.926] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:41:0) after 1m0s
[19:23:23.053] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:41:0)
[19:23:23.053] Timer tick, asking Buildbucket for the build status
[19:23:23.443] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:23.443] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:42:0) after 1m0s
[19:24:23.532] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:42:0)
[19:24:23.532] Timer tick, asking Buildbucket for the build status
[19:24:24.659] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:24.659] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:43:0) after 1m0s
[19:25:24.780] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:43:0)
[19:25:24.780] Timer tick, asking Buildbucket for the build status
[19:25:24.845] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:24.845] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:44:0) after 1m0s
[19:26:25.382] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:44:0)
[19:26:25.382] Timer tick, asking Buildbucket for the build status
[19:26:26.511] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:26.511] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:45:0) after 1m0s
[19:27:26.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:45:0)
[19:27:26.773] Timer tick, asking Buildbucket for the build status
[19:27:26.955] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:26.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:46:0) after 1m0s
[19:28:27.219] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:46:0)
[19:28:27.219] Timer tick, asking Buildbucket for the build status
[19:28:27.309] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:27.309] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:47:0) after 1m0s
[19:29:27.461] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:47:0)
[19:29:27.461] Timer tick, asking Buildbucket for the build status
[19:29:28.362] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:28.362] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:48:0) after 1m0s
[19:30:28.523] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:48:0)
[19:30:28.523] Timer tick, asking Buildbucket for the build status
[19:30:28.657] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:28.657] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:49:0) after 1m0s
[19:31:28.752] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:49:0)
[19:31:28.752] Timer tick, asking Buildbucket for the build status
[19:31:31.332] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:31.332] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:50:0) after 1m0s
[19:32:31.460] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:50:0)
[19:32:31.460] Timer tick, asking Buildbucket for the build status
[19:32:31.611] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:31.611] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:51:0) after 1m0s
[19:33:31.662] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:51:0)
[19:33:31.662] Timer tick, asking Buildbucket for the build status
[19:33:32.476] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:32.476] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:52:0) after 1m0s
[19:34:32.502] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:52:0)
[19:34:32.502] Timer tick, asking Buildbucket for the build status
[19:34:32.600] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:32.600] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:53:0) after 1m0s
[19:35:32.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:53:0)
[19:35:32.627] Timer tick, asking Buildbucket for the build status
[19:35:32.816] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:32.816] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:54:0) after 1m0s
[19:36:32.880] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:54:0)
[19:36:32.880] Timer tick, asking Buildbucket for the build status
[19:36:33.141] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:33.141] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:55:0) after 1m0s
[19:37:33.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:55:0)
[19:37:33.121] Timer tick, asking Buildbucket for the build status
[19:37:33.276] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:33.276] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:56:0) after 1m0s
[19:38:33.630] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:56:0)
[19:38:33.630] Timer tick, asking Buildbucket for the build status
[19:38:33.775] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:33.775] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:57:0) after 1m0s
[19:39:33.966] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:57:0)
[19:39:33.966] Timer tick, asking Buildbucket for the build status
[19:39:34.719] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:34.719] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:58:0) after 1m0s
[19:40:34.849] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:58:0)
[19:40:34.849] Timer tick, asking Buildbucket for the build status
[19:40:35.036] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:35.036] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:59:0) after 1m0s
[19:41:35.157] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:59:0)
[19:41:35.157] Timer tick, asking Buildbucket for the build status
[19:41:35.297] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:35.297] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:60:0) after 1m0s
[19:42:35.531] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:60:0)
[19:42:35.531] Timer tick, asking Buildbucket for the build status
[19:42:35.610] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:35.610] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:61:0) after 1m0s
[19:43:35.646] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:61:0)
[19:43:35.646] Timer tick, asking Buildbucket for the build status
[19:43:36.716] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:36.716] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:62:0) after 1m0s
[19:44:36.777] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:62:0)
[19:44:36.777] Timer tick, asking Buildbucket for the build status
[19:44:37.175] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:37.175] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:63:0) after 1m0s
[19:45:37.302] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:63:0)
[19:45:37.302] Timer tick, asking Buildbucket for the build status
[19:45:37.857] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:37.857] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:64:0) after 1m0s
[19:46:37.940] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:64:0)
[19:46:37.940] Timer tick, asking Buildbucket for the build status
[19:46:38.888] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:38.888] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:65:0) after 1m0s
[19:47:39.032] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:65:0)
[19:47:39.032] Timer tick, asking Buildbucket for the build status
[19:47:42.366] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:42.366] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:66:0) after 1m0s
[19:48:42.439] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:66:0)
[19:48:42.439] Timer tick, asking Buildbucket for the build status
[19:48:42.506] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:42.506] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:67:0) after 1m0s
[19:49:42.539] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:67:0)
[19:49:42.539] Timer tick, asking Buildbucket for the build status
[19:49:43.221] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:43.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:68:0) after 1m0s
[19:50:43.246] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:68:0)
[19:50:43.246] Timer tick, asking Buildbucket for the build status
[19:50:43.314] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:43.314] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:69:0) after 1m0s
[19:51:45.514] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:69:0)
[19:51:45.514] Timer tick, asking Buildbucket for the build status
[19:51:46.104] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:46.105] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:70:0) after 1m0s
[19:52:46.129] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:70:0)
[19:52:46.129] Timer tick, asking Buildbucket for the build status
[19:52:46.359] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:46.359] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:71:0) after 1m0s
[19:53:46.335] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:71:0)
[19:53:46.335] Timer tick, asking Buildbucket for the build status
[19:53:46.930] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:46.930] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:72:0) after 1m0s
[19:54:46.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:72:0)
[19:54:46.953] Timer tick, asking Buildbucket for the build status
[19:54:47.145] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:47.145] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:73:0) after 1m0s
[19:55:47.184] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:73:0)
[19:55:47.184] Timer tick, asking Buildbucket for the build status
[19:55:47.279] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:47.279] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:74:0) after 1m0s
[19:56:47.370] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:74:0)
[19:56:47.370] Timer tick, asking Buildbucket for the build status
[19:56:50.519] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:50.519] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:75:0) after 1m0s
[19:57:50.537] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:75:0)
[19:57:50.537] Timer tick, asking Buildbucket for the build status
[19:57:51.292] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:51.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:76:0) after 1m0s
[19:58:51.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:76:0)
[19:58:51.361] Timer tick, asking Buildbucket for the build status
[19:58:51.499] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:51.499] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:77:0) after 1m0s
[19:59:51.477] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:77:0)
[19:59:51.477] Timer tick, asking Buildbucket for the build status
[19:59:51.573] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:51.573] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:78:0) after 1m0s
[20:00:51.600] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:78:0)
[20:00:51.600] Timer tick, asking Buildbucket for the build status
[20:00:51.752] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:51.752] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:79:0) after 1m0s
[20:01:51.775] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:79:0)
[20:01:51.775] Timer tick, asking Buildbucket for the build status
[20:01:51.830] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:51.830] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:80:0) after 1m0s
[20:02:51.854] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:80:0)
[20:02:51.854] Timer tick, asking Buildbucket for the build status
[20:02:51.927] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:51.927] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:81:0) after 1m0s
[20:03:51.949] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:81:0)
[20:03:51.949] Timer tick, asking Buildbucket for the build status
[20:03:52.106] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:52.106] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:82:0) after 1m0s
[20:04:52.162] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:82:0)
[20:04:52.162] Timer tick, asking Buildbucket for the build status
[20:04:52.232] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:52.232] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:83:0) after 1m0s
[20:05:52.291] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:83:0)
[20:05:52.291] Timer tick, asking Buildbucket for the build status
[20:05:52.361] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:52.361] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:84:0) after 1m0s
[20:06:52.400] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:84:0)
[20:06:52.400] Timer tick, asking Buildbucket for the build status
[20:06:52.485] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:52.485] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:85:0) after 1m0s
[20:07:52.563] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:85:0)
[20:07:52.563] Timer tick, asking Buildbucket for the build status
[20:07:54.281] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:54.281] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:86:0) after 1m0s
[20:08:54.340] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:86:0)
[20:08:54.340] Timer tick, asking Buildbucket for the build status
[20:08:54.480] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:54.480] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:87:0) after 1m0s
[20:09:54.503] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:87:0)
[20:09:54.503] Timer tick, asking Buildbucket for the build status
[20:09:54.646] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:54.646] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:88:0) after 1m0s
[20:10:54.622] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:88:0)
[20:10:54.622] Timer tick, asking Buildbucket for the build status
[20:10:54.724] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:54.724] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:89:0) after 1m0s
[20:11:54.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:89:0)
[20:11:54.762] Timer tick, asking Buildbucket for the build status
[20:11:55.721] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:55.721] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:90:0) after 1m0s
[20:12:55.779] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:90:0)
[20:12:55.779] Timer tick, asking Buildbucket for the build status
[20:12:59.664] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:59.664] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:91:0) after 1m0s
[20:13:59.687] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:91:0)
[20:13:59.687] Timer tick, asking Buildbucket for the build status
[20:14:00.459] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:00.459] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:92:0) after 1m0s
[20:15:00.590] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:92:0)
[20:15:00.590] Timer tick, asking Buildbucket for the build status
[20:15:00.781] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:00.781] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:93:0) after 1m0s
[20:16:00.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:93:0)
[20:16:00.761] Timer tick, asking Buildbucket for the build status
[20:16:00.817] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:00.818] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:94:0) after 1m0s
[20:17:00.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:94:0)
[20:17:00.876] Timer tick, asking Buildbucket for the build status
[20:17:01.221] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:01.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:95:0) after 1m0s
[20:18:01.257] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:95:0)
[20:18:01.257] Timer tick, asking Buildbucket for the build status
[20:18:04.783] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:04.783] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:96:0) after 1m0s
[20:19:04.815] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:96:0)
[20:19:04.815] Timer tick, asking Buildbucket for the build status
[20:19:04.910] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:04.910] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:97:0) after 1m0s
[20:20:04.932] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:97:0)
[20:20:04.932] Timer tick, asking Buildbucket for the build status
[20:20:05.001] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:05.001] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:98:0) after 1m0s
[20:21:05.054] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:98:0)
[20:21:05.054] Timer tick, asking Buildbucket for the build status
[20:21:05.520] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:05.520] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:99:0) after 1m0s
[20:22:05.618] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:99:0)
[20:22:05.618] Timer tick, asking Buildbucket for the build status
[20:22:06.170] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:06.170] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:100:0) after 1m0s
[20:23:06.233] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:100:0)
[20:23:06.233] Timer tick, asking Buildbucket for the build status
[20:23:06.397] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:06.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:101:0) after 1m0s
[20:24:06.627] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:101:0)
[20:24:06.627] Timer tick, asking Buildbucket for the build status
[20:24:07.597] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:07.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:102:0) after 1m0s
[20:25:07.602] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:102:0)
[20:25:07.602] Timer tick, asking Buildbucket for the build status
[20:25:07.956] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:07.956] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:103:0) after 1m0s
[20:26:08.005] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:103:0)
[20:26:08.005] Timer tick, asking Buildbucket for the build status
[20:26:08.064] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:08.064] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:104:0) after 1m0s
[20:27:08.077] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:104:0)
[20:27:08.077] Timer tick, asking Buildbucket for the build status
[20:27:08.589] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:08.589] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:105:0) after 1m0s
[20:28:08.691] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:105:0)
[20:28:08.691] Timer tick, asking Buildbucket for the build status
[20:28:08.763] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:08.763] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:106:0) after 1m0s
[20:29:09.043] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:106:0)
[20:29:09.043] Timer tick, asking Buildbucket for the build status
[20:29:09.203] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:09.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:107:0) after 1m0s
[20:30:09.224] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:107:0)
[20:30:09.224] Timer tick, asking Buildbucket for the build status
[20:30:09.538] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:09.538] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:108:0) after 1m0s
[20:31:09.516] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:108:0)
[20:31:09.516] Timer tick, asking Buildbucket for the build status
[20:31:09.585] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:09.585] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:109:0) after 1m0s
[20:32:09.645] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:109:0)
[20:32:09.645] Timer tick, asking Buildbucket for the build status
[20:32:09.751] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:09.751] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:110:0) after 1m0s
[20:33:09.848] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:110:0)
[20:33:09.848] Timer tick, asking Buildbucket for the build status
[20:33:14.225] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:14.225] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:111:0) after 1m0s
[20:34:14.484] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:111:0)
[20:34:14.484] Timer tick, asking Buildbucket for the build status
[20:34:14.619] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:14.619] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:112:0) after 1m0s
[20:35:14.687] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:112:0)
[20:35:14.687] Timer tick, asking Buildbucket for the build status
[20:35:14.785] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:14.785] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:113:0) after 1m0s
[20:36:14.862] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:113:0)
[20:36:14.862] Timer tick, asking Buildbucket for the build status
[20:36:15.025] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:15.025] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:114:0) after 1m0s
[20:37:15.262] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:114:0)
[20:37:15.262] Timer tick, asking Buildbucket for the build status
[20:37:18.090] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:18.090] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:115:0) after 1m0s
[20:38:18.169] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:115:0)
[20:38:18.169] Timer tick, asking Buildbucket for the build status
[20:38:18.443] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:18.443] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:116:0) after 1m0s
[20:39:18.648] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:116:0)
[20:39:18.648] Timer tick, asking Buildbucket for the build status
[20:39:18.911] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:18.911] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:117:0) after 1m0s
[20:40:19.094] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:117:0)
[20:40:19.094] Timer tick, asking Buildbucket for the build status
[20:40:20.328] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:20.328] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:118:0) after 1m0s
[20:41:20.532] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:118:0)
[20:41:20.532] Timer tick, asking Buildbucket for the build status
[20:41:20.788] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:20.788] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:119:0) after 1m0s
[20:42:20.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:119:0)
[20:42:20.969] Timer tick, asking Buildbucket for the build status
[20:42:21.134] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:21.134] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:120:0) after 1m0s
[20:43:21.295] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:120:0)
[20:43:21.295] Timer tick, asking Buildbucket for the build status
[20:43:21.353] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:21.353] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:121:0) after 1m0s
[20:44:21.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:121:0)
[20:44:21.561] Timer tick, asking Buildbucket for the build status
[20:44:21.673] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:21.673] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:122:0) after 1m0s
[20:45:21.832] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:122:0)
[20:45:21.832] Timer tick, asking Buildbucket for the build status
[20:45:22.174] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:22.174] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:123:0) after 1m0s
[20:46:22.327] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:123:0)
[20:46:22.327] Timer tick, asking Buildbucket for the build status
[20:46:22.427] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:22.427] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:124:0) after 1m0s
[20:47:22.465] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:124:0)
[20:47:22.465] Timer tick, asking Buildbucket for the build status
[20:47:23.393] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:23.393] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:125:0) after 1m0s
[20:48:23.423] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:125:0)
[20:48:23.423] Timer tick, asking Buildbucket for the build status
[20:48:23.592] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:23.592] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:126:0) after 1m0s
[20:49:23.660] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:126:0)
[20:49:23.660] Timer tick, asking Buildbucket for the build status
[20:49:23.940] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:23.940] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:127:0) after 1m0s
[20:50:24.021] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:127:0)
[20:50:24.021] Timer tick, asking Buildbucket for the build status
[20:50:24.119] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:24.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:128:0) after 1m0s
[20:51:24.186] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:128:0)
[20:51:24.186] Timer tick, asking Buildbucket for the build status
[20:51:24.357] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:24.357] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:129:0) after 1m0s
[20:52:24.438] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:129:0)
[20:52:24.438] Timer tick, asking Buildbucket for the build status
[20:52:24.522] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:24.522] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:130:0) after 1m0s
[20:53:24.561] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:130:0)
[20:53:24.561] Timer tick, asking Buildbucket for the build status
[20:53:24.961] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:24.961] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:131:0) after 1m0s
[20:54:25.053] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:131:0)
[20:54:25.053] Timer tick, asking Buildbucket for the build status
[20:54:25.530] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:25.530] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:132:0) after 1m0s
[20:55:25.626] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:132:0)
[20:55:25.626] Timer tick, asking Buildbucket for the build status
[20:55:25.922] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:25.922] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:133:0) after 1m0s
[20:56:25.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:133:0)
[20:56:25.956] Timer tick, asking Buildbucket for the build status
[20:56:26.219] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:26.219] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:134:0) after 1m0s
[20:57:26.230] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:134:0)
[20:57:26.230] Timer tick, asking Buildbucket for the build status
[20:57:27.736] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:27.736] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:135:0) after 1m0s
[20:58:27.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:135:0)
[20:58:27.761] Timer tick, asking Buildbucket for the build status
[20:58:27.894] Build 8861728842457401984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:27.894] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9027158904487944496:136:0) after 1m0s
[20:58:53.810] Received PubSub notification, asking Buildbucket for the build status
[20:58:54.000] Build 8861728842457401984: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:58:54.000] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607201932504663",
"created_by": "project:chromium",
"created_ts": "1607193826199875",
"id": "8861728842457401984",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0241bf61c124f5791745218ea98c7122c114e00d\"}}",
"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\": \"0241bf61c124f5791745218ea98c7122c114e00d\", \"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-8z7f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1058, \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"c532048062befdee8bb57d18f01d3a3c403d49de\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"0241bf61c124f5791745218ea98c7122c114e00d\", \"got_revision_cp\": \"refs/heads/master@{#834032}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"f36e874ade1dbc6f5ddfc857753a892a8bb358c3\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0241bf61c124f5791745218ea98c7122c114e00d\"}, \"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-8z7f\"], \"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\": [\"5640-d0eb544\"], \"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": "1607194095758452",
"status": "COMPLETED",
"status_changed_ts": "1607201933069150",
"tags": [
"build_address:luci.chromium.ci/win32-official/1058",
"builder:win32-official",
"buildset:commit/git/0241bf61c124f5791745218ea98c7122c114e00d",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0241bf61c124f5791745218ea98c7122c114e00d",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027158904487944496",
"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/8861728842457401984/+/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:504d091f12042710",
"user_agent:luci-scheduler"
],
"updated_ts": "1607201933106216",
"url": "https://ci.chromium.org/b/8861728842457401984",
"utcnow_ts": "1607201933976915"
}
}
[20:58:54.000] Invocation finished in 2h15m9.121862893s with status SUCCEEDED