[21:10:38.818] New invocation is queued and will start shortly
[21:10:40.067] Starting the invocation (attempt 1)
[21:10:40.111] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:10:40.111] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:10:40.111] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9034941001531753472",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5a600233f4a48ac694a4311256eb02ddf06c5318\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9034941001531753472",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/5a600233f4a48ac694a4311256eb02ddf06c5318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a600233f4a48ac694a4311256eb02ddf06c5318",
"gitiles_ref:refs/heads/master"
]
}
[21:10:43.617] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1599772240212472",
"id": "8869510939405616944",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a600233f4a48ac694a4311256eb02ddf06c5318\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599772241286664",
"tags": [
"build_address:luci.chromium.ci/win32-official/505",
"builder:win32-official",
"buildset:commit/git/5a600233f4a48ac694a4311256eb02ddf06c5318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a600233f4a48ac694a4311256eb02ddf06c5318",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034941001531753472",
"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/8869510939405616944/+/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": "1599772241287169",
"url": "https://ci.chromium.org/b/8869510939405616944",
"utcnow_ts": "1599772243595428"
}
}
[21:10:43.617] Task URL: https://ci.chromium.org/b/8869510939405616944
[21:10:43.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:2:0) after 1m0s
[21:11:14.983] Received PubSub notification, asking Buildbucket for the build status
[21:11:15.072] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:43.652] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:2:0)
[21:11:43.652] Timer tick, asking Buildbucket for the build status
[21:11:43.992] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:43.992] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:4:0) after 1m0s
[21:12:44.024] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:4:0)
[21:12:44.024] Timer tick, asking Buildbucket for the build status
[21:12:44.198] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:44.198] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:5:0) after 1m0s
[21:13:44.282] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:5:0)
[21:13:44.283] Timer tick, asking Buildbucket for the build status
[21:13:44.495] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:44.495] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:6:0) after 1m0s
[21:14:45.601] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:6:0)
[21:14:45.601] Timer tick, asking Buildbucket for the build status
[21:14:45.928] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:45.928] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:7:0) after 1m0s
[21:15:46.134] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:7:0)
[21:15:46.134] Timer tick, asking Buildbucket for the build status
[21:15:46.648] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:46.648] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:8:0) after 1m0s
[21:16:46.918] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:8:0)
[21:16:46.918] Timer tick, asking Buildbucket for the build status
[21:16:47.062] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:47.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:9:0) after 1m0s
[21:17:47.356] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:9:0)
[21:17:47.356] Timer tick, asking Buildbucket for the build status
[21:17:47.585] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:47.585] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:10:0) after 1m0s
[21:18:47.761] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:10:0)
[21:18:47.761] Timer tick, asking Buildbucket for the build status
[21:18:47.840] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:47.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:11:0) after 1m0s
[21:19:48.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:11:0)
[21:19:48.064] Timer tick, asking Buildbucket for the build status
[21:19:48.384] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:48.384] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:12:0) after 1m0s
[21:20:48.639] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:12:0)
[21:20:48.639] Timer tick, asking Buildbucket for the build status
[21:20:48.824] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:48.824] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:13:0) after 1m0s
[21:21:48.938] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:13:0)
[21:21:48.938] Timer tick, asking Buildbucket for the build status
[21:21:49.036] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:49.036] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:14:0) after 1m0s
[21:22:49.054] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:14:0)
[21:22:49.054] Timer tick, asking Buildbucket for the build status
[21:22:49.323] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:49.323] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:15:0) after 1m0s
[21:23:49.421] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:15:0)
[21:23:49.421] Timer tick, asking Buildbucket for the build status
[21:23:49.584] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:49.584] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:16:0) after 1m0s
[21:24:49.682] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:16:0)
[21:24:49.682] Timer tick, asking Buildbucket for the build status
[21:24:49.789] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:49.789] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:17:0) after 1m0s
[21:25:49.923] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:17:0)
[21:25:49.923] Timer tick, asking Buildbucket for the build status
[21:25:50.068] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:50.068] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:18:0) after 1m0s
[21:26:50.348] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:18:0)
[21:26:50.348] Timer tick, asking Buildbucket for the build status
[21:26:50.789] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:50.789] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:19:0) after 1m0s
[21:27:50.995] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:19:0)
[21:27:50.995] Timer tick, asking Buildbucket for the build status
[21:27:51.127] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:51.127] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:20:0) after 1m0s
[21:28:51.288] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:20:0)
[21:28:51.288] Timer tick, asking Buildbucket for the build status
[21:28:51.418] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:51.418] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:21:0) after 1m0s
[21:29:51.442] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:21:0)
[21:29:51.442] Timer tick, asking Buildbucket for the build status
[21:29:51.530] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:51.530] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:22:0) after 1m0s
[21:30:51.630] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:22:0)
[21:30:51.631] Timer tick, asking Buildbucket for the build status
[21:30:51.808] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:51.808] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:23:0) after 1m0s
[21:31:51.967] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:23:0)
[21:31:51.967] Timer tick, asking Buildbucket for the build status
[21:31:52.118] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:52.118] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:24:0) after 1m0s
[21:32:52.163] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:24:0)
[21:32:52.163] Timer tick, asking Buildbucket for the build status
[21:32:52.390] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:52.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:25:0) after 1m0s
[21:33:52.511] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:25:0)
[21:33:52.511] Timer tick, asking Buildbucket for the build status
[21:33:52.584] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:52.584] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:26:0) after 1m0s
[21:34:52.614] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:26:0)
[21:34:52.614] Timer tick, asking Buildbucket for the build status
[21:34:52.910] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:52.910] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:27:0) after 1m0s
[21:35:53.000] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:27:0)
[21:35:53.000] Timer tick, asking Buildbucket for the build status
[21:35:53.256] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:53.256] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:28:0) after 1m0s
[21:36:53.341] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:28:0)
[21:36:53.341] Timer tick, asking Buildbucket for the build status
[21:36:54.092] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:54.092] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:29:0) after 1m0s
[21:37:54.215] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:29:0)
[21:37:54.215] Timer tick, asking Buildbucket for the build status
[21:37:54.436] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:54.436] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:30:0) after 1m0s
[21:38:54.496] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:30:0)
[21:38:54.496] Timer tick, asking Buildbucket for the build status
[21:38:54.710] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:54.710] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:31:0) after 1m0s
[21:39:54.800] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:31:0)
[21:39:54.800] Timer tick, asking Buildbucket for the build status
[21:39:54.872] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:54.872] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:32:0) after 1m0s
[21:40:55.037] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:32:0)
[21:40:55.037] Timer tick, asking Buildbucket for the build status
[21:40:55.159] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:55.159] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:33:0) after 1m0s
[21:41:55.170] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:33:0)
[21:41:55.170] Timer tick, asking Buildbucket for the build status
[21:41:55.270] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:55.270] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:34:0) after 1m0s
[21:42:55.349] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:34:0)
[21:42:55.349] Timer tick, asking Buildbucket for the build status
[21:42:55.473] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:55.473] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:35:0) after 1m0s
[21:43:55.531] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:35:0)
[21:43:55.531] Timer tick, asking Buildbucket for the build status
[21:43:55.828] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:55.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:36:0) after 1m0s
[21:44:55.931] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:36:0)
[21:44:55.931] Timer tick, asking Buildbucket for the build status
[21:44:56.001] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:56.001] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:37:0) after 1m0s
[21:45:56.113] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:37:0)
[21:45:56.113] Timer tick, asking Buildbucket for the build status
[21:45:56.399] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:56.399] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:38:0) after 1m0s
[21:46:56.895] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:38:0)
[21:46:56.895] Timer tick, asking Buildbucket for the build status
[21:46:57.061] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:57.063] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:39:0) after 1m0s
[21:47:57.085] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:39:0)
[21:47:57.085] Timer tick, asking Buildbucket for the build status
[21:47:57.159] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:57.159] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:40:0) after 1m0s
[21:48:57.216] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:40:0)
[21:48:57.216] Timer tick, asking Buildbucket for the build status
[21:48:57.345] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:57.345] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:41:0) after 1m0s
[21:49:57.460] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:41:0)
[21:49:57.460] Timer tick, asking Buildbucket for the build status
[21:49:57.598] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:57.598] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:42:0) after 1m0s
[21:50:58.228] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:42:0)
[21:50:58.228] Timer tick, asking Buildbucket for the build status
[21:50:58.404] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:58.404] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:43:0) after 1m0s
[21:51:58.467] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:43:0)
[21:51:58.467] Timer tick, asking Buildbucket for the build status
[21:51:58.587] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:58.587] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:44:0) after 1m0s
[21:52:58.684] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:44:0)
[21:52:58.684] Timer tick, asking Buildbucket for the build status
[21:52:58.838] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:58.838] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:45:0) after 1m0s
[21:53:59.039] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:45:0)
[21:53:59.039] Timer tick, asking Buildbucket for the build status
[21:53:59.115] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:59.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:46:0) after 1m0s
[21:54:59.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:46:0)
[21:54:59.173] Timer tick, asking Buildbucket for the build status
[21:54:59.243] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:59.243] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:47:0) after 1m0s
[21:55:59.273] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:47:0)
[21:55:59.273] Timer tick, asking Buildbucket for the build status
[21:55:59.437] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:59.437] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:48:0) after 1m0s
[21:56:59.578] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:48:0)
[21:56:59.578] Timer tick, asking Buildbucket for the build status
[21:57:00.206] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:00.206] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:49:0) after 1m0s
[21:58:00.213] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:49:0)
[21:58:00.213] Timer tick, asking Buildbucket for the build status
[21:58:00.342] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:00.342] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:50:0) after 1m0s
[21:59:00.774] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:50:0)
[21:59:00.774] Timer tick, asking Buildbucket for the build status
[21:59:01.002] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:01.002] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:51:0) after 1m0s
[22:00:01.983] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:51:0)
[22:00:01.983] Timer tick, asking Buildbucket for the build status
[22:00:02.119] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:02.119] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:52:0) after 1m0s
[22:01:02.278] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:52:0)
[22:01:02.278] Timer tick, asking Buildbucket for the build status
[22:01:03.612] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:03.612] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:53:0) after 1m0s
[22:02:03.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:53:0)
[22:02:03.624] Timer tick, asking Buildbucket for the build status
[22:02:03.708] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:03.708] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:54:0) after 1m0s
[22:03:03.731] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:54:0)
[22:03:03.731] Timer tick, asking Buildbucket for the build status
[22:03:03.898] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:03.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:55:0) after 1m0s
[22:04:03.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:55:0)
[22:04:03.946] Timer tick, asking Buildbucket for the build status
[22:04:04.028] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:04.028] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:56:0) after 1m0s
[22:05:04.146] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:56:0)
[22:05:04.146] Timer tick, asking Buildbucket for the build status
[22:05:04.230] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:04.230] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:57:0) after 1m0s
[22:06:04.305] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:57:0)
[22:06:04.305] Timer tick, asking Buildbucket for the build status
[22:06:04.803] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:04.803] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:58:0) after 1m0s
[22:07:04.865] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:58:0)
[22:07:04.865] Timer tick, asking Buildbucket for the build status
[22:07:05.047] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:05.047] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:59:0) after 1m0s
[22:08:05.141] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:59:0)
[22:08:05.141] Timer tick, asking Buildbucket for the build status
[22:08:05.221] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:05.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:60:0) after 1m0s
[22:09:05.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:60:0)
[22:09:05.323] Timer tick, asking Buildbucket for the build status
[22:09:05.639] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:05.639] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:61:0) after 1m0s
[22:10:05.781] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:61:0)
[22:10:05.781] Timer tick, asking Buildbucket for the build status
[22:10:05.851] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:05.865] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:62:0) after 1m0s
[22:11:05.913] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:62:0)
[22:11:05.914] Timer tick, asking Buildbucket for the build status
[22:11:06.113] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:06.113] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:63:0) after 1m0s
[22:12:06.180] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:63:0)
[22:12:06.180] Timer tick, asking Buildbucket for the build status
[22:12:06.262] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:06.262] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:64:0) after 1m0s
[22:13:06.270] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:64:0)
[22:13:06.270] Timer tick, asking Buildbucket for the build status
[22:13:06.443] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:06.443] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:65:0) after 1m0s
[22:14:06.469] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:65:0)
[22:14:06.469] Timer tick, asking Buildbucket for the build status
[22:14:06.546] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:06.546] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:66:0) after 1m0s
[22:15:06.722] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:66:0)
[22:15:06.722] Timer tick, asking Buildbucket for the build status
[22:15:06.840] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:06.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:67:0) after 1m0s
[22:16:06.858] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:67:0)
[22:16:06.858] Timer tick, asking Buildbucket for the build status
[22:16:07.541] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:07.541] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:68:0) after 1m0s
[22:17:07.558] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:68:0)
[22:17:07.558] Timer tick, asking Buildbucket for the build status
[22:17:07.689] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:07.689] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:69:0) after 1m0s
[22:18:07.769] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:69:0)
[22:18:07.769] Timer tick, asking Buildbucket for the build status
[22:18:07.920] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:07.920] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:70:0) after 1m0s
[22:19:07.914] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:70:0)
[22:19:07.914] Timer tick, asking Buildbucket for the build status
[22:19:08.046] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:08.046] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:71:0) after 1m0s
[22:20:08.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:71:0)
[22:20:08.071] Timer tick, asking Buildbucket for the build status
[22:20:08.205] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:08.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:72:0) after 1m0s
[22:21:08.266] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:72:0)
[22:21:08.266] Timer tick, asking Buildbucket for the build status
[22:21:08.376] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:08.376] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:73:0) after 1m0s
[22:22:08.425] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:73:0)
[22:22:08.425] Timer tick, asking Buildbucket for the build status
[22:22:08.541] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:08.541] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:74:0) after 1m0s
[22:23:08.590] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:74:0)
[22:23:08.590] Timer tick, asking Buildbucket for the build status
[22:23:08.722] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:08.722] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:75:0) after 1m0s
[22:24:08.942] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:75:0)
[22:24:08.942] Timer tick, asking Buildbucket for the build status
[22:24:09.022] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:09.022] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:76:0) after 1m0s
[22:25:09.114] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:76:0)
[22:25:09.114] Timer tick, asking Buildbucket for the build status
[22:25:09.318] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:09.318] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:77:0) after 1m0s
[22:26:09.445] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:77:0)
[22:26:09.445] Timer tick, asking Buildbucket for the build status
[22:26:09.705] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:09.705] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:78:0) after 1m0s
[22:27:09.728] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:78:0)
[22:27:09.728] Timer tick, asking Buildbucket for the build status
[22:27:09.919] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:09.919] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:79:0) after 1m0s
[22:28:09.981] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:79:0)
[22:28:09.981] Timer tick, asking Buildbucket for the build status
[22:28:10.292] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:10.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:80:0) after 1m0s
[22:29:10.415] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:80:0)
[22:29:10.415] Timer tick, asking Buildbucket for the build status
[22:29:10.509] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:10.509] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:81:0) after 1m0s
[22:30:10.588] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:81:0)
[22:30:10.588] Timer tick, asking Buildbucket for the build status
[22:30:10.813] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:10.813] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:82:0) after 1m0s
[22:31:10.912] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:82:0)
[22:31:10.912] Timer tick, asking Buildbucket for the build status
[22:31:11.137] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:11.137] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:83:0) after 1m0s
[22:32:11.158] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:83:0)
[22:32:11.158] Timer tick, asking Buildbucket for the build status
[22:32:11.317] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:11.317] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:84:0) after 1m0s
[22:33:12.079] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:84:0)
[22:33:12.079] Timer tick, asking Buildbucket for the build status
[22:33:12.314] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:12.314] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:85:0) after 1m0s
[22:34:12.410] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:85:0)
[22:34:12.410] Timer tick, asking Buildbucket for the build status
[22:34:12.677] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:12.677] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:86:0) after 1m0s
[22:35:12.766] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:86:0)
[22:35:12.766] Timer tick, asking Buildbucket for the build status
[22:35:13.140] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:13.140] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:87:0) after 1m0s
[22:36:13.362] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:87:0)
[22:36:13.362] Timer tick, asking Buildbucket for the build status
[22:36:13.613] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:88:0) after 1m0s
[22:37:13.717] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:88:0)
[22:37:13.717] Timer tick, asking Buildbucket for the build status
[22:37:13.803] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:13.803] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:89:0) after 1m0s
[22:38:14.109] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:89:0)
[22:38:14.109] Timer tick, asking Buildbucket for the build status
[22:38:14.240] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:14.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:90:0) after 1m0s
[22:39:14.276] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:90:0)
[22:39:14.276] Timer tick, asking Buildbucket for the build status
[22:39:14.349] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:14.349] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:91:0) after 1m0s
[22:40:14.528] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:91:0)
[22:40:14.528] Timer tick, asking Buildbucket for the build status
[22:40:14.615] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:14.615] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:92:0) after 1m0s
[22:41:14.863] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:92:0)
[22:41:14.863] Timer tick, asking Buildbucket for the build status
[22:41:15.213] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:15.213] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:93:0) after 1m0s
[22:42:15.279] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:93:0)
[22:42:15.279] Timer tick, asking Buildbucket for the build status
[22:42:15.609] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:15.609] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:94:0) after 1m0s
[22:43:15.674] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:94:0)
[22:43:15.674] Timer tick, asking Buildbucket for the build status
[22:43:15.741] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:15.741] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:95:0) after 1m0s
[22:44:15.799] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:95:0)
[22:44:15.799] Timer tick, asking Buildbucket for the build status
[22:44:15.907] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:15.907] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:96:0) after 1m0s
[22:45:16.019] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:96:0)
[22:45:16.019] Timer tick, asking Buildbucket for the build status
[22:45:16.292] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:16.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:97:0) after 1m0s
[22:46:16.297] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:97:0)
[22:46:16.297] Timer tick, asking Buildbucket for the build status
[22:46:16.437] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:16.437] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:98:0) after 1m0s
[22:47:16.507] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:98:0)
[22:47:16.507] Timer tick, asking Buildbucket for the build status
[22:47:16.579] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:16.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:99:0) after 1m0s
[22:48:16.589] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:99:0)
[22:48:16.589] Timer tick, asking Buildbucket for the build status
[22:48:16.727] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:16.727] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:100:0) after 1m0s
[22:49:16.861] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:100:0)
[22:49:16.861] Timer tick, asking Buildbucket for the build status
[22:49:17.205] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:17.205] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:101:0) after 1m0s
[22:50:17.270] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:101:0)
[22:50:17.270] Timer tick, asking Buildbucket for the build status
[22:50:17.404] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:17.404] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:102:0) after 1m0s
[22:51:17.477] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:102:0)
[22:51:17.477] Timer tick, asking Buildbucket for the build status
[22:51:17.601] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:17.601] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:103:0) after 1m0s
[22:52:17.708] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:103:0)
[22:52:17.708] Timer tick, asking Buildbucket for the build status
[22:52:17.833] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:17.833] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:104:0) after 1m0s
[22:53:17.949] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:104:0)
[22:53:17.949] Timer tick, asking Buildbucket for the build status
[22:53:18.018] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:18.018] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:105:0) after 1m0s
[22:54:18.041] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:105:0)
[22:54:18.041] Timer tick, asking Buildbucket for the build status
[22:54:18.238] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:18.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:106:0) after 1m0s
[22:55:18.376] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:106:0)
[22:55:18.376] Timer tick, asking Buildbucket for the build status
[22:55:18.450] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:18.450] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:107:0) after 1m0s
[22:56:18.565] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:107:0)
[22:56:18.565] Timer tick, asking Buildbucket for the build status
[22:56:18.944] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:18.944] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:108:0) after 1m0s
[22:57:19.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:108:0)
[22:57:19.084] Timer tick, asking Buildbucket for the build status
[22:57:19.343] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:19.343] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:109:0) after 1m0s
[22:58:19.455] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:109:0)
[22:58:19.455] Timer tick, asking Buildbucket for the build status
[22:58:20.086] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:20.086] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:110:0) after 1m0s
[22:59:20.181] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:110:0)
[22:59:20.181] Timer tick, asking Buildbucket for the build status
[22:59:20.263] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:20.263] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:111:0) after 1m0s
[23:00:20.303] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:111:0)
[23:00:20.303] Timer tick, asking Buildbucket for the build status
[23:00:20.617] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:20.617] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:112:0) after 1m0s
[23:01:20.721] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:112:0)
[23:01:20.721] Timer tick, asking Buildbucket for the build status
[23:01:20.929] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:20.929] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:113:0) after 1m0s
[23:02:20.997] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:113:0)
[23:02:20.997] Timer tick, asking Buildbucket for the build status
[23:02:21.072] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:21.072] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:114:0) after 1m0s
[23:03:21.217] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:114:0)
[23:03:21.217] Timer tick, asking Buildbucket for the build status
[23:03:21.560] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:21.560] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:115:0) after 1m0s
[23:04:21.592] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:115:0)
[23:04:21.592] Timer tick, asking Buildbucket for the build status
[23:04:21.967] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:21.967] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:116:0) after 1m0s
[23:05:22.006] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:116:0)
[23:05:22.006] Timer tick, asking Buildbucket for the build status
[23:05:22.215] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:22.215] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:117:0) after 1m0s
[23:06:22.269] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:117:0)
[23:06:22.269] Timer tick, asking Buildbucket for the build status
[23:06:22.409] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:22.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:118:0) after 1m0s
[23:07:22.457] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:118:0)
[23:07:22.457] Timer tick, asking Buildbucket for the build status
[23:07:22.697] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:22.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:119:0) after 1m0s
[23:08:22.758] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:119:0)
[23:08:22.758] Timer tick, asking Buildbucket for the build status
[23:08:22.840] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:22.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:120:0) after 1m0s
[23:09:22.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:120:0)
[23:09:22.928] Timer tick, asking Buildbucket for the build status
[23:09:23.046] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:23.046] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:121:0) after 1m0s
[23:10:23.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:121:0)
[23:10:23.131] Timer tick, asking Buildbucket for the build status
[23:10:23.366] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:23.366] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:122:0) after 1m0s
[23:11:23.362] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:122:0)
[23:11:23.362] Timer tick, asking Buildbucket for the build status
[23:11:23.919] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:23.919] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:123:0) after 1m0s
[23:12:23.928] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:123:0)
[23:12:23.928] Timer tick, asking Buildbucket for the build status
[23:12:24.193] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:24.193] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:124:0) after 1m0s
[23:13:24.322] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:124:0)
[23:13:24.322] Timer tick, asking Buildbucket for the build status
[23:13:24.534] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:24.534] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:125:0) after 1m0s
[23:14:24.737] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:125:0)
[23:14:24.737] Timer tick, asking Buildbucket for the build status
[23:14:24.826] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:24.826] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:126:0) after 1m0s
[23:15:25.085] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:126:0)
[23:15:25.085] Timer tick, asking Buildbucket for the build status
[23:15:25.218] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:25.218] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:127:0) after 1m0s
[23:16:25.241] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:127:0)
[23:16:25.241] Timer tick, asking Buildbucket for the build status
[23:16:25.681] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:25.681] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:128:0) after 1m0s
[23:17:25.762] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:128:0)
[23:17:25.762] Timer tick, asking Buildbucket for the build status
[23:17:25.988] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:25.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:129:0) after 1m0s
[23:18:26.132] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:129:0)
[23:18:26.132] Timer tick, asking Buildbucket for the build status
[23:18:26.222] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:26.222] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:130:0) after 1m0s
[23:19:26.385] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:130:0)
[23:19:26.385] Timer tick, asking Buildbucket for the build status
[23:19:26.464] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:26.465] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:131:0) after 1m0s
[23:20:27.487] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:131:0)
[23:20:27.487] Timer tick, asking Buildbucket for the build status
[23:20:27.673] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:27.673] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:132:0) after 1m0s
[23:21:27.745] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:132:0)
[23:21:27.745] Timer tick, asking Buildbucket for the build status
[23:21:28.169] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:28.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:133:0) after 1m0s
[23:22:28.386] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:133:0)
[23:22:28.386] Timer tick, asking Buildbucket for the build status
[23:22:28.477] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:28.477] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:134:0) after 1m0s
[23:23:28.599] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:134:0)
[23:23:28.599] Timer tick, asking Buildbucket for the build status
[23:23:28.662] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:28.662] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:135:0) after 1m0s
[23:24:28.717] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:135:0)
[23:24:28.717] Timer tick, asking Buildbucket for the build status
[23:24:28.835] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:28.835] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:136:0) after 1m0s
[23:25:29.017] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:136:0)
[23:25:29.017] Timer tick, asking Buildbucket for the build status
[23:25:29.288] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:29.288] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:137:0) after 1m0s
[23:26:29.588] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:137:0)
[23:26:29.588] Timer tick, asking Buildbucket for the build status
[23:26:29.929] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:29.929] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:138:0) after 1m0s
[23:27:30.009] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:138:0)
[23:27:30.009] Timer tick, asking Buildbucket for the build status
[23:27:30.297] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:30.297] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:139:0) after 1m0s
[23:28:30.357] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:139:0)
[23:28:30.357] Timer tick, asking Buildbucket for the build status
[23:28:30.666] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:30.666] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:140:0) after 1m0s
[23:29:30.731] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:140:0)
[23:29:30.731] Timer tick, asking Buildbucket for the build status
[23:29:30.926] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:30.926] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:141:0) after 1m0s
[23:30:31.167] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:141:0)
[23:30:31.167] Timer tick, asking Buildbucket for the build status
[23:30:31.549] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:31.549] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:142:0) after 1m0s
[23:31:31.629] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:142:0)
[23:31:31.629] Timer tick, asking Buildbucket for the build status
[23:31:31.787] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:31.787] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:143:0) after 1m0s
[23:32:31.806] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:143:0)
[23:32:31.806] Timer tick, asking Buildbucket for the build status
[23:32:31.873] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:31.873] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:144:0) after 1m0s
[23:33:31.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:144:0)
[23:33:31.956] Timer tick, asking Buildbucket for the build status
[23:33:32.036] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:32.036] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:145:0) after 1m0s
[23:34:32.083] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:145:0)
[23:34:32.083] Timer tick, asking Buildbucket for the build status
[23:34:32.322] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:32.322] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:146:0) after 1m0s
[23:35:32.350] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:146:0)
[23:35:32.350] Timer tick, asking Buildbucket for the build status
[23:35:32.432] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:32.432] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:147:0) after 1m0s
[23:36:32.497] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:147:0)
[23:36:32.497] Timer tick, asking Buildbucket for the build status
[23:36:32.691] Build 8869510939405616944: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:32.691] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9034941001531753472:148:0) after 1m0s
[23:36:59.727] Received PubSub notification, asking Buildbucket for the build status
[23:36:59.831] Build 8869510939405616944: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:36:59.831] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1599781018594858",
"created_by": "project:chromium",
"created_ts": "1599772240212472",
"id": "8869510939405616944",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a600233f4a48ac694a4311256eb02ddf06c5318\"}}",
"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\": \"5a600233f4a48ac694a4311256eb02ddf06c5318\", \"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-1-732a\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 505, \"got_angle_revision\": \"65dcbe124508f9799348fd4828cbb0f76cf80671\", \"got_dawn_revision\": \"9427c97d9c2917d66e79e49ffaa718aac2e7d6ad\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"5a600233f4a48ac694a4311256eb02ddf06c5318\", \"got_revision_cp\": \"refs/heads/master@{#805893}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"41974f57973ebcdab4cfde6cc60354f6b79a5901\", \"got_v8_revision\": \"bb4817544d7ce327fc432a8229efa213952ca979\", \"got_v8_revision_cp\": \"refs/heads/8.7.76@{#1}\", \"got_webrtc_revision\": \"fc83cdc8198b0609f8986313ef48e565b558e8a7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32071}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5a600233f4a48ac694a4311256eb02ddf06c5318\"}, \"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-1-732a\"], \"image\": [\"chrome-win10-1703-20083100-22dcea0839e\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5513-ee65082\"], \"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": "1599772274162579",
"status": "COMPLETED",
"status_changed_ts": "1599781018980262",
"tags": [
"build_address:luci.chromium.ci/win32-official/505",
"builder:win32-official",
"buildset:commit/git/5a600233f4a48ac694a4311256eb02ddf06c5318",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5a600233f4a48ac694a4311256eb02ddf06c5318",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034941001531753472",
"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/8869510939405616944/+/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:4e92acb7bd206d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599781018987036",
"url": "https://ci.chromium.org/b/8869510939405616944",
"utcnow_ts": "1599781019820510"
}
}
[23:36:59.831] Invocation finished in 2h26m21.030764803s with status SUCCEEDED