[01:29:07.042] New invocation is queued and will start shortly
[01:29:08.547] Starting the invocation (attempt 1)
[01:29:08.611] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:29:08.611] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:29:08.611] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025502655472732144",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"39b939f13f6b15d749b41e0fa1744c814f209962\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9025502655472732144",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/39b939f13f6b15d749b41e0fa1744c814f209962",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39b939f13f6b15d749b41e0fa1744c814f209962",
"gitiles_ref:refs/heads/master"
]
}
[01:29:09.988] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608773348730874",
"id": "8860072593040631504",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39b939f13f6b15d749b41e0fa1744c814f209962\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608773349704358",
"tags": [
"build_address:luci.chromium.ci/win32-official/1180",
"builder:win32-official",
"buildset:commit/git/39b939f13f6b15d749b41e0fa1744c814f209962",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39b939f13f6b15d749b41e0fa1744c814f209962",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025502655472732144",
"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/8860072593040631504/+/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": "1608773349704519",
"url": "https://ci.chromium.org/b/8860072593040631504",
"utcnow_ts": "1608773349976933"
}
}
[01:29:09.988] Task URL: https://ci.chromium.org/b/8860072593040631504
[01:29:09.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:2:0) after 1m0s
[01:30:02.769] Received PubSub notification, asking Buildbucket for the build status
[01:30:02.855] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:10.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:2:0)
[01:30:10.067] Timer tick, asking Buildbucket for the build status
[01:30:10.129] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:10.129] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:4:0) after 1m0s
[01:31:10.284] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:4:0)
[01:31:10.284] Timer tick, asking Buildbucket for the build status
[01:31:10.491] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:10.491] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:5:0) after 1m0s
[01:32:10.586] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:5:0)
[01:32:10.586] Timer tick, asking Buildbucket for the build status
[01:32:10.669] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:10.669] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:6:0) after 1m0s
[01:33:10.802] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:6:0)
[01:33:10.802] Timer tick, asking Buildbucket for the build status
[01:33:11.019] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:11.019] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:7:0) after 1m0s
[01:34:11.111] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:7:0)
[01:34:11.111] Timer tick, asking Buildbucket for the build status
[01:34:11.234] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:11.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:8:0) after 1m0s
[01:35:11.325] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:8:0)
[01:35:11.325] Timer tick, asking Buildbucket for the build status
[01:35:11.436] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:11.436] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:9:0) after 1m0s
[01:36:11.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:9:0)
[01:36:11.521] Timer tick, asking Buildbucket for the build status
[01:36:11.790] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:11.790] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:10:0) after 1m0s
[01:37:11.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:10:0)
[01:37:11.845] Timer tick, asking Buildbucket for the build status
[01:37:11.967] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:11.967] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:11:0) after 1m0s
[01:38:11.965] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:11:0)
[01:38:11.965] Timer tick, asking Buildbucket for the build status
[01:38:12.054] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:12.054] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:12:0) after 1m0s
[01:39:12.085] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:12:0)
[01:39:12.085] Timer tick, asking Buildbucket for the build status
[01:39:12.594] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:12.594] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:13:0) after 1m0s
[01:40:12.655] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:13:0)
[01:40:12.655] Timer tick, asking Buildbucket for the build status
[01:40:12.775] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:12.775] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:14:0) after 1m0s
[01:41:12.825] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:14:0)
[01:41:12.825] Timer tick, asking Buildbucket for the build status
[01:41:12.898] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:12.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:15:0) after 1m0s
[01:42:12.923] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:15:0)
[01:42:12.923] Timer tick, asking Buildbucket for the build status
[01:42:12.983] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:12.983] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:16:0) after 1m0s
[01:43:13.086] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:16:0)
[01:43:13.086] Timer tick, asking Buildbucket for the build status
[01:43:13.378] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:13.378] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:17:0) after 1m0s
[01:44:13.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:17:0)
[01:44:13.450] Timer tick, asking Buildbucket for the build status
[01:44:13.576] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:13.576] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:18:0) after 1m0s
[01:45:13.555] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:18:0)
[01:45:13.555] Timer tick, asking Buildbucket for the build status
[01:45:13.649] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:13.649] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:19:0) after 1m0s
[01:46:13.697] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:19:0)
[01:46:13.697] Timer tick, asking Buildbucket for the build status
[01:46:13.771] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:13.771] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:20:0) after 1m0s
[01:47:13.801] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:20:0)
[01:47:13.801] Timer tick, asking Buildbucket for the build status
[01:47:13.893] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:13.893] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:21:0) after 1m0s
[01:48:13.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:21:0)
[01:48:13.941] Timer tick, asking Buildbucket for the build status
[01:48:14.188] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:14.188] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:22:0) after 1m0s
[01:49:14.292] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:22:0)
[01:49:14.292] Timer tick, asking Buildbucket for the build status
[01:49:15.370] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:15.370] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:23:0) after 1m0s
[01:50:15.470] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:23:0)
[01:50:15.470] Timer tick, asking Buildbucket for the build status
[01:50:15.592] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:15.592] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:24:0) after 1m0s
[01:51:15.691] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:24:0)
[01:51:15.691] Timer tick, asking Buildbucket for the build status
[01:51:15.897] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:15.897] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:25:0) after 1m0s
[01:52:15.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:25:0)
[01:52:15.953] Timer tick, asking Buildbucket for the build status
[01:52:16.132] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:16.132] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:26:0) after 1m0s
[01:53:16.165] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:26:0)
[01:53:16.165] Timer tick, asking Buildbucket for the build status
[01:53:16.240] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:16.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:27:0) after 1m0s
[01:54:16.389] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:27:0)
[01:54:16.390] Timer tick, asking Buildbucket for the build status
[01:54:16.491] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:16.491] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:28:0) after 1m0s
[01:55:16.610] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:28:0)
[01:55:16.610] Timer tick, asking Buildbucket for the build status
[01:55:16.878] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:16.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:29:0) after 1m0s
[01:56:16.873] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:29:0)
[01:56:16.873] Timer tick, asking Buildbucket for the build status
[01:56:16.940] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:16.940] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:30:0) after 1m0s
[01:57:16.966] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:30:0)
[01:57:16.966] Timer tick, asking Buildbucket for the build status
[01:57:17.295] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:17.295] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:31:0) after 1m0s
[01:58:17.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:31:0)
[01:58:17.354] Timer tick, asking Buildbucket for the build status
[01:58:17.909] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:17.909] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:32:0) after 1m0s
[01:59:17.940] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:32:0)
[01:59:17.940] Timer tick, asking Buildbucket for the build status
[01:59:18.292] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:18.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:33:0) after 1m0s
[02:00:18.433] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:33:0)
[02:00:18.433] Timer tick, asking Buildbucket for the build status
[02:00:19.629] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:19.629] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:34:0) after 1m0s
[02:01:19.799] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:34:0)
[02:01:19.799] Timer tick, asking Buildbucket for the build status
[02:01:19.911] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:19.911] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:35:0) after 1m0s
[02:02:19.993] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:35:0)
[02:02:19.993] Timer tick, asking Buildbucket for the build status
[02:02:20.053] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:20.053] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:36:0) after 1m0s
[02:03:20.078] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:36:0)
[02:03:20.078] Timer tick, asking Buildbucket for the build status
[02:03:20.214] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:03:20.214] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:37:0) after 1m0s
[02:04:20.279] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:37:0)
[02:04:20.279] Timer tick, asking Buildbucket for the build status
[02:04:20.361] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:04:20.361] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:38:0) after 1m0s
[02:05:20.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:38:0)
[02:05:20.354] Timer tick, asking Buildbucket for the build status
[02:05:20.460] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:20.460] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:39:0) after 1m0s
[02:06:20.585] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:39:0)
[02:06:20.585] Timer tick, asking Buildbucket for the build status
[02:06:20.752] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:20.752] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:40:0) after 1m0s
[02:07:20.863] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:40:0)
[02:07:20.863] Timer tick, asking Buildbucket for the build status
[02:07:21.110] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:07:21.110] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:41:0) after 1m0s
[02:08:21.269] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:41:0)
[02:08:21.269] Timer tick, asking Buildbucket for the build status
[02:08:21.340] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:08:21.340] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:42:0) after 1m0s
[02:09:21.358] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:42:0)
[02:09:21.358] Timer tick, asking Buildbucket for the build status
[02:09:21.469] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:09:21.469] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:43:0) after 1m0s
[02:10:21.513] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:43:0)
[02:10:21.513] Timer tick, asking Buildbucket for the build status
[02:10:21.588] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:10:21.588] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:44:0) after 1m0s
[02:11:21.641] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:44:0)
[02:11:21.641] Timer tick, asking Buildbucket for the build status
[02:11:21.829] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:11:21.829] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:45:0) after 1m0s
[02:12:21.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:45:0)
[02:12:21.885] Timer tick, asking Buildbucket for the build status
[02:12:21.934] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:12:21.934] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:46:0) after 1m0s
[02:13:22.061] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:46:0)
[02:13:22.061] Timer tick, asking Buildbucket for the build status
[02:13:22.198] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:13:22.198] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:47:0) after 1m0s
[02:14:22.318] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:47:0)
[02:14:22.318] Timer tick, asking Buildbucket for the build status
[02:14:22.405] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:22.405] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:48:0) after 1m0s
[02:15:22.416] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:48:0)
[02:15:22.416] Timer tick, asking Buildbucket for the build status
[02:15:22.585] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:22.585] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:49:0) after 1m0s
[02:16:22.631] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:49:0)
[02:16:22.631] Timer tick, asking Buildbucket for the build status
[02:16:22.680] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:22.680] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:50:0) after 1m0s
[02:17:22.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:50:0)
[02:17:22.740] Timer tick, asking Buildbucket for the build status
[02:17:22.800] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:22.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:51:0) after 1m0s
[02:18:22.832] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:51:0)
[02:18:22.832] Timer tick, asking Buildbucket for the build status
[02:18:22.890] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:22.890] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:52:0) after 1m0s
[02:19:22.942] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:52:0)
[02:19:22.942] Timer tick, asking Buildbucket for the build status
[02:19:23.037] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:23.037] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:53:0) after 1m0s
[02:20:23.035] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:53:0)
[02:20:23.035] Timer tick, asking Buildbucket for the build status
[02:20:23.095] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:23.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:54:0) after 1m0s
[02:21:23.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:54:0)
[02:21:23.095] Timer tick, asking Buildbucket for the build status
[02:21:23.160] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:23.160] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:55:0) after 1m0s
[02:22:23.260] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:55:0)
[02:22:23.260] Timer tick, asking Buildbucket for the build status
[02:22:23.347] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:23.347] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:56:0) after 1m0s
[02:23:23.428] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:56:0)
[02:23:23.428] Timer tick, asking Buildbucket for the build status
[02:23:23.502] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:23.502] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:57:0) after 1m0s
[02:24:23.707] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:57:0)
[02:24:23.708] Timer tick, asking Buildbucket for the build status
[02:24:23.826] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:23.826] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:58:0) after 1m0s
[02:25:24.200] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:58:0)
[02:25:24.200] Timer tick, asking Buildbucket for the build status
[02:25:24.255] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:24.255] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:59:0) after 1m0s
[02:26:24.477] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:59:0)
[02:26:24.477] Timer tick, asking Buildbucket for the build status
[02:26:24.846] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:24.846] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:60:0) after 1m0s
[02:27:24.931] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:60:0)
[02:27:24.931] Timer tick, asking Buildbucket for the build status
[02:27:25.116] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:25.116] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:61:0) after 1m0s
[02:28:25.243] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:61:0)
[02:28:25.243] Timer tick, asking Buildbucket for the build status
[02:28:25.316] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:25.316] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:62:0) after 1m0s
[02:29:25.725] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:62:0)
[02:29:25.725] Timer tick, asking Buildbucket for the build status
[02:29:25.830] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:25.830] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:63:0) after 1m0s
[02:30:25.919] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:63:0)
[02:30:25.919] Timer tick, asking Buildbucket for the build status
[02:30:26.034] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:26.034] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:64:0) after 1m0s
[02:31:26.084] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:64:0)
[02:31:26.084] Timer tick, asking Buildbucket for the build status
[02:31:26.472] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:26.472] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:65:0) after 1m0s
[02:32:26.482] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:65:0)
[02:32:26.482] Timer tick, asking Buildbucket for the build status
[02:32:26.556] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:26.556] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:66:0) after 1m0s
[02:33:26.686] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:66:0)
[02:33:26.686] Timer tick, asking Buildbucket for the build status
[02:33:26.921] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:26.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:67:0) after 1m0s
[02:34:26.948] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:67:0)
[02:34:26.948] Timer tick, asking Buildbucket for the build status
[02:34:27.039] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:27.039] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:68:0) after 1m0s
[02:35:27.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:68:0)
[02:35:27.076] Timer tick, asking Buildbucket for the build status
[02:35:27.148] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:27.148] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:69:0) after 1m0s
[02:36:27.178] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:69:0)
[02:36:27.178] Timer tick, asking Buildbucket for the build status
[02:36:27.240] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:27.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:70:0) after 1m0s
[02:37:27.349] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:70:0)
[02:37:27.349] Timer tick, asking Buildbucket for the build status
[02:37:27.419] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:27.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:71:0) after 1m0s
[02:38:27.498] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:71:0)
[02:38:27.498] Timer tick, asking Buildbucket for the build status
[02:38:27.566] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:27.566] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:72:0) after 1m0s
[02:39:27.621] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:72:0)
[02:39:27.621] Timer tick, asking Buildbucket for the build status
[02:39:28.182] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:28.183] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:73:0) after 1m0s
[02:40:28.262] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:73:0)
[02:40:28.262] Timer tick, asking Buildbucket for the build status
[02:40:28.327] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:28.327] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:74:0) after 1m0s
[02:41:28.402] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:74:0)
[02:41:28.402] Timer tick, asking Buildbucket for the build status
[02:41:28.465] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:28.465] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:75:0) after 1m0s
[02:42:28.499] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:75:0)
[02:42:28.499] Timer tick, asking Buildbucket for the build status
[02:42:28.549] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:28.549] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:76:0) after 1m0s
[02:43:28.581] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:76:0)
[02:43:28.581] Timer tick, asking Buildbucket for the build status
[02:43:28.857] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:28.857] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:77:0) after 1m0s
[02:44:28.912] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:77:0)
[02:44:28.912] Timer tick, asking Buildbucket for the build status
[02:44:29.016] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:29.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:78:0) after 1m0s
[02:45:29.098] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:78:0)
[02:45:29.098] Timer tick, asking Buildbucket for the build status
[02:45:29.169] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:29.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:79:0) after 1m0s
[02:46:29.324] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:79:0)
[02:46:29.324] Timer tick, asking Buildbucket for the build status
[02:46:29.385] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:29.385] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:80:0) after 1m0s
[02:47:29.423] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:80:0)
[02:47:29.423] Timer tick, asking Buildbucket for the build status
[02:47:29.488] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:29.488] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:81:0) after 1m0s
[02:48:29.524] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:81:0)
[02:48:29.524] Timer tick, asking Buildbucket for the build status
[02:48:29.594] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:29.594] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:82:0) after 1m0s
[02:49:29.730] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:82:0)
[02:49:29.730] Timer tick, asking Buildbucket for the build status
[02:49:29.860] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:29.860] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:83:0) after 1m0s
[02:50:29.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:83:0)
[02:50:29.956] Timer tick, asking Buildbucket for the build status
[02:50:30.023] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:30.023] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:84:0) after 1m0s
[02:51:30.202] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:84:0)
[02:51:30.202] Timer tick, asking Buildbucket for the build status
[02:51:30.317] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:30.317] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:85:0) after 1m0s
[02:52:30.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:85:0)
[02:52:30.342] Timer tick, asking Buildbucket for the build status
[02:52:30.409] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:30.409] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:86:0) after 1m0s
[02:53:30.520] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:86:0)
[02:53:30.520] Timer tick, asking Buildbucket for the build status
[02:53:30.710] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:30.710] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:87:0) after 1m0s
[02:54:30.771] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:87:0)
[02:54:30.771] Timer tick, asking Buildbucket for the build status
[02:54:30.877] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:30.877] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:88:0) after 1m0s
[02:55:30.904] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:88:0)
[02:55:30.904] Timer tick, asking Buildbucket for the build status
[02:55:31.009] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:31.009] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:89:0) after 1m0s
[02:56:31.094] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:89:0)
[02:56:31.094] Timer tick, asking Buildbucket for the build status
[02:56:31.346] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:31.346] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:90:0) after 1m0s
[02:57:31.453] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:90:0)
[02:57:31.453] Timer tick, asking Buildbucket for the build status
[02:57:31.582] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:31.582] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:91:0) after 1m0s
[02:58:31.621] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:91:0)
[02:58:31.621] Timer tick, asking Buildbucket for the build status
[02:58:31.704] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:31.704] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:92:0) after 1m0s
[02:59:31.730] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:92:0)
[02:59:31.730] Timer tick, asking Buildbucket for the build status
[02:59:31.838] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:31.838] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:93:0) after 1m0s
[03:00:32.198] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:93:0)
[03:00:32.198] Timer tick, asking Buildbucket for the build status
[03:00:32.346] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:32.346] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:94:0) after 1m0s
[03:01:32.462] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:94:0)
[03:01:32.462] Timer tick, asking Buildbucket for the build status
[03:01:32.558] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:32.559] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:95:0) after 1m0s
[03:02:32.664] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:95:0)
[03:02:32.664] Timer tick, asking Buildbucket for the build status
[03:02:32.863] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:32.863] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:96:0) after 1m0s
[03:03:32.909] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:96:0)
[03:03:32.909] Timer tick, asking Buildbucket for the build status
[03:03:33.066] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:33.066] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:97:0) after 1m0s
[03:04:33.049] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:97:0)
[03:04:33.049] Timer tick, asking Buildbucket for the build status
[03:04:33.317] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:33.317] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:98:0) after 1m0s
[03:05:33.459] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:98:0)
[03:05:33.459] Timer tick, asking Buildbucket for the build status
[03:05:33.548] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:33.548] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:99:0) after 1m0s
[03:06:33.591] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:99:0)
[03:06:33.591] Timer tick, asking Buildbucket for the build status
[03:06:33.671] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:33.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:100:0) after 1m0s
[03:07:33.770] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:100:0)
[03:07:33.770] Timer tick, asking Buildbucket for the build status
[03:07:33.822] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:33.823] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:101:0) after 1m0s
[03:08:33.922] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:101:0)
[03:08:33.922] Timer tick, asking Buildbucket for the build status
[03:08:35.116] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:35.116] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:102:0) after 1m0s
[03:09:35.137] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:102:0)
[03:09:35.137] Timer tick, asking Buildbucket for the build status
[03:09:35.230] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:35.230] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:103:0) after 1m0s
[03:10:35.327] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:103:0)
[03:10:35.327] Timer tick, asking Buildbucket for the build status
[03:10:35.411] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:35.411] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:104:0) after 1m0s
[03:11:35.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:104:0)
[03:11:35.740] Timer tick, asking Buildbucket for the build status
[03:11:35.808] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:35.808] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:105:0) after 1m0s
[03:12:36.048] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:105:0)
[03:12:36.048] Timer tick, asking Buildbucket for the build status
[03:12:36.217] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:36.217] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:106:0) after 1m0s
[03:13:36.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:106:0)
[03:13:36.323] Timer tick, asking Buildbucket for the build status
[03:13:36.457] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:36.457] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:107:0) after 1m0s
[03:14:36.499] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:107:0)
[03:14:36.499] Timer tick, asking Buildbucket for the build status
[03:14:36.596] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:36.596] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:108:0) after 1m0s
[03:15:36.732] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:108:0)
[03:15:36.732] Timer tick, asking Buildbucket for the build status
[03:15:36.797] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:36.797] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:109:0) after 1m0s
[03:16:36.902] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:109:0)
[03:16:36.902] Timer tick, asking Buildbucket for the build status
[03:16:36.967] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:36.967] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:110:0) after 1m0s
[03:17:36.996] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:110:0)
[03:17:36.996] Timer tick, asking Buildbucket for the build status
[03:17:37.048] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:37.048] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:111:0) after 1m0s
[03:18:37.193] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:111:0)
[03:18:37.193] Timer tick, asking Buildbucket for the build status
[03:18:37.263] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:37.263] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:112:0) after 1m0s
[03:19:37.382] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:112:0)
[03:19:37.382] Timer tick, asking Buildbucket for the build status
[03:19:37.493] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:37.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:113:0) after 1m0s
[03:20:37.617] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:113:0)
[03:20:37.617] Timer tick, asking Buildbucket for the build status
[03:20:37.684] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:37.684] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:114:0) after 1m0s
[03:21:37.718] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:114:0)
[03:21:37.718] Timer tick, asking Buildbucket for the build status
[03:21:37.786] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:37.786] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:115:0) after 1m0s
[03:22:37.880] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:115:0)
[03:22:37.880] Timer tick, asking Buildbucket for the build status
[03:22:38.083] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:38.083] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:116:0) after 1m0s
[03:23:38.167] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:116:0)
[03:23:38.167] Timer tick, asking Buildbucket for the build status
[03:23:38.224] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:38.224] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:117:0) after 1m0s
[03:24:38.331] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:117:0)
[03:24:38.331] Timer tick, asking Buildbucket for the build status
[03:24:38.487] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:38.487] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:118:0) after 1m0s
[03:25:38.552] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:118:0)
[03:25:38.552] Timer tick, asking Buildbucket for the build status
[03:25:39.004] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:39.004] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:119:0) after 1m0s
[03:26:39.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:119:0)
[03:26:39.095] Timer tick, asking Buildbucket for the build status
[03:26:39.163] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:39.163] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:120:0) after 1m0s
[03:27:39.241] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:120:0)
[03:27:39.241] Timer tick, asking Buildbucket for the build status
[03:27:39.307] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:39.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:121:0) after 1m0s
[03:28:39.480] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:121:0)
[03:28:39.481] Timer tick, asking Buildbucket for the build status
[03:28:39.550] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:39.550] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:122:0) after 1m0s
[03:29:39.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:122:0)
[03:29:39.690] Timer tick, asking Buildbucket for the build status
[03:29:39.800] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:39.800] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:123:0) after 1m0s
[03:30:39.849] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:123:0)
[03:30:39.849] Timer tick, asking Buildbucket for the build status
[03:30:40.216] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:40.216] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:124:0) after 1m0s
[03:31:40.238] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:124:0)
[03:31:40.238] Timer tick, asking Buildbucket for the build status
[03:31:40.691] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:40.692] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:125:0) after 1m0s
[03:32:40.724] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:125:0)
[03:32:40.724] Timer tick, asking Buildbucket for the build status
[03:32:41.002] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:41.002] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:126:0) after 1m0s
[03:33:41.027] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:126:0)
[03:33:41.027] Timer tick, asking Buildbucket for the build status
[03:33:41.095] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:41.095] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:127:0) after 1m0s
[03:34:41.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:127:0)
[03:34:41.125] Timer tick, asking Buildbucket for the build status
[03:34:41.239] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:41.239] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:128:0) after 1m0s
[03:35:41.262] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:128:0)
[03:35:41.262] Timer tick, asking Buildbucket for the build status
[03:35:41.340] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:41.340] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:129:0) after 1m0s
[03:36:41.361] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:129:0)
[03:36:41.361] Timer tick, asking Buildbucket for the build status
[03:36:42.082] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:42.082] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:130:0) after 1m0s
[03:37:42.267] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:130:0)
[03:37:42.267] Timer tick, asking Buildbucket for the build status
[03:37:42.382] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:42.382] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:131:0) after 1m0s
[03:38:42.843] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:131:0)
[03:38:42.843] Timer tick, asking Buildbucket for the build status
[03:38:42.936] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:42.936] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:132:0) after 1m0s
[03:39:43.393] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:132:0)
[03:39:43.393] Timer tick, asking Buildbucket for the build status
[03:39:43.493] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:43.493] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:133:0) after 1m0s
[03:40:43.729] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:133:0)
[03:40:43.729] Timer tick, asking Buildbucket for the build status
[03:40:43.794] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:43.794] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:134:0) after 1m0s
[03:41:44.594] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:134:0)
[03:41:44.594] Timer tick, asking Buildbucket for the build status
[03:41:44.675] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:44.675] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:135:0) after 1m0s
[03:42:44.861] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:135:0)
[03:42:44.861] Timer tick, asking Buildbucket for the build status
[03:42:45.012] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:45.012] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:136:0) after 1m0s
[03:43:45.457] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:136:0)
[03:43:45.457] Timer tick, asking Buildbucket for the build status
[03:43:45.805] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:45.805] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:137:0) after 1m0s
[03:44:45.913] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:137:0)
[03:44:45.913] Timer tick, asking Buildbucket for the build status
[03:44:46.889] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:46.889] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:138:0) after 1m0s
[03:45:47.011] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:138:0)
[03:45:47.011] Timer tick, asking Buildbucket for the build status
[03:45:47.086] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:47.086] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:139:0) after 1m0s
[03:46:47.789] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:139:0)
[03:46:47.789] Timer tick, asking Buildbucket for the build status
[03:46:47.861] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:47.861] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:140:0) after 1m0s
[03:47:48.181] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:140:0)
[03:47:48.181] Timer tick, asking Buildbucket for the build status
[03:47:48.336] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:48.336] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:141:0) after 1m0s
[03:48:48.599] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:141:0)
[03:48:48.599] Timer tick, asking Buildbucket for the build status
[03:48:48.703] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:48.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:142:0) after 1m0s
[03:49:48.977] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:142:0)
[03:49:48.977] Timer tick, asking Buildbucket for the build status
[03:49:49.070] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:49.070] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:143:0) after 1m0s
[03:50:49.601] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:143:0)
[03:50:49.601] Timer tick, asking Buildbucket for the build status
[03:50:49.671] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:49.671] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:144:0) after 1m0s
[03:51:49.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:144:0)
[03:51:49.804] Timer tick, asking Buildbucket for the build status
[03:51:49.901] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:49.901] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:145:0) after 1m0s
[03:52:49.958] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:145:0)
[03:52:49.958] Timer tick, asking Buildbucket for the build status
[03:52:50.218] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:50.218] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:146:0) after 1m0s
[03:53:51.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:146:0)
[03:53:51.574] Timer tick, asking Buildbucket for the build status
[03:53:51.636] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:51.636] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:147:0) after 1m0s
[03:54:51.800] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:147:0)
[03:54:51.800] Timer tick, asking Buildbucket for the build status
[03:54:51.898] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:51.898] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:148:0) after 1m0s
[03:55:53.122] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:148:0)
[03:55:53.122] Timer tick, asking Buildbucket for the build status
[03:55:53.203] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:53.203] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:149:0) after 1m0s
[03:56:53.239] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:149:0)
[03:56:53.239] Timer tick, asking Buildbucket for the build status
[03:56:53.324] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:53.324] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:150:0) after 1m0s
[03:57:53.404] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:150:0)
[03:57:53.404] Timer tick, asking Buildbucket for the build status
[03:57:53.482] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:53.482] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:151:0) after 1m0s
[03:58:53.624] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:151:0)
[03:58:53.624] Timer tick, asking Buildbucket for the build status
[03:58:53.801] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:53.801] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:152:0) after 1m0s
[03:59:53.855] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:152:0)
[03:59:53.855] Timer tick, asking Buildbucket for the build status
[03:59:54.007] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:54.007] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:153:0) after 1m0s
[04:00:54.061] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:153:0)
[04:00:54.061] Timer tick, asking Buildbucket for the build status
[04:00:54.113] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:54.113] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:154:0) after 1m0s
[04:01:54.140] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:154:0)
[04:01:54.140] Timer tick, asking Buildbucket for the build status
[04:01:54.210] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:54.210] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:155:0) after 1m0s
[04:02:54.334] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:155:0)
[04:02:54.334] Timer tick, asking Buildbucket for the build status
[04:02:54.422] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:54.422] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:156:0) after 1m0s
[04:03:54.496] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:156:0)
[04:03:54.496] Timer tick, asking Buildbucket for the build status
[04:03:54.549] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:54.549] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:157:0) after 1m0s
[04:04:54.593] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:157:0)
[04:04:54.593] Timer tick, asking Buildbucket for the build status
[04:04:55.112] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:55.112] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:158:0) after 1m0s
[04:05:55.160] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:158:0)
[04:05:55.160] Timer tick, asking Buildbucket for the build status
[04:05:55.226] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:55.227] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:159:0) after 1m0s
[04:06:55.304] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:159:0)
[04:06:55.304] Timer tick, asking Buildbucket for the build status
[04:06:55.359] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:55.359] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:160:0) after 1m0s
[04:07:55.415] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:160:0)
[04:07:55.415] Timer tick, asking Buildbucket for the build status
[04:07:55.613] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:55.613] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:161:0) after 1m0s
[04:08:55.682] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:161:0)
[04:08:55.682] Timer tick, asking Buildbucket for the build status
[04:08:55.745] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:55.745] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:162:0) after 1m0s
[04:09:55.775] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:162:0)
[04:09:55.775] Timer tick, asking Buildbucket for the build status
[04:09:55.874] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:55.874] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:163:0) after 1m0s
[04:10:55.932] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:163:0)
[04:10:55.932] Timer tick, asking Buildbucket for the build status
[04:10:55.997] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:55.997] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:164:0) after 1m0s
[04:11:56.061] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:164:0)
[04:11:56.061] Timer tick, asking Buildbucket for the build status
[04:11:56.128] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:56.128] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:165:0) after 1m0s
[04:12:56.219] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:165:0)
[04:12:56.219] Timer tick, asking Buildbucket for the build status
[04:12:56.289] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:56.289] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:166:0) after 1m0s
[04:13:56.381] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:166:0)
[04:13:56.381] Timer tick, asking Buildbucket for the build status
[04:13:56.596] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:56.596] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:167:0) after 1m0s
[04:14:56.747] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:167:0)
[04:14:56.747] Timer tick, asking Buildbucket for the build status
[04:14:56.840] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:56.840] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:168:0) after 1m0s
[04:15:56.893] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:168:0)
[04:15:56.893] Timer tick, asking Buildbucket for the build status
[04:15:56.958] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:56.958] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:169:0) after 1m0s
[04:16:57.024] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:169:0)
[04:16:57.024] Timer tick, asking Buildbucket for the build status
[04:16:57.093] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:57.093] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:170:0) after 1m0s
[04:17:57.141] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:170:0)
[04:17:57.141] Timer tick, asking Buildbucket for the build status
[04:17:57.193] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:57.193] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:171:0) after 1m0s
[04:18:57.287] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:171:0)
[04:18:57.287] Timer tick, asking Buildbucket for the build status
[04:18:57.515] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:57.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:172:0) after 1m0s
[04:19:57.617] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:172:0)
[04:19:57.617] Timer tick, asking Buildbucket for the build status
[04:19:57.714] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:57.714] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:173:0) after 1m0s
[04:20:57.734] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:173:0)
[04:20:57.734] Timer tick, asking Buildbucket for the build status
[04:20:57.871] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:57.871] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:174:0) after 1m0s
[04:21:57.871] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:174:0)
[04:21:57.872] Timer tick, asking Buildbucket for the build status
[04:21:57.928] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:57.928] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:175:0) after 1m0s
[04:22:57.992] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:175:0)
[04:22:57.992] Timer tick, asking Buildbucket for the build status
[04:22:58.070] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:58.070] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:176:0) after 1m0s
[04:23:58.136] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:176:0)
[04:23:58.136] Timer tick, asking Buildbucket for the build status
[04:23:58.231] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:58.231] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:177:0) after 1m0s
[04:24:58.295] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:177:0)
[04:24:58.295] Timer tick, asking Buildbucket for the build status
[04:24:58.427] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:58.427] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:178:0) after 1m0s
[04:25:58.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:178:0)
[04:25:58.508] Timer tick, asking Buildbucket for the build status
[04:25:58.579] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:58.579] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:179:0) after 1m0s
[04:26:58.621] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:179:0)
[04:26:58.621] Timer tick, asking Buildbucket for the build status
[04:26:58.703] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:58.703] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:180:0) after 1m0s
[04:27:58.727] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:180:0)
[04:27:58.727] Timer tick, asking Buildbucket for the build status
[04:27:58.783] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:58.783] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:181:0) after 1m0s
[04:28:58.881] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:181:0)
[04:28:58.881] Timer tick, asking Buildbucket for the build status
[04:28:59.619] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:59.619] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:182:0) after 1m0s
[04:29:59.682] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:182:0)
[04:29:59.682] Timer tick, asking Buildbucket for the build status
[04:29:59.849] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:59.849] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:183:0) after 1m0s
[04:30:59.927] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:183:0)
[04:30:59.927] Timer tick, asking Buildbucket for the build status
[04:30:59.986] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:59.986] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:184:0) after 1m0s
[04:32:00.032] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:184:0)
[04:32:00.032] Timer tick, asking Buildbucket for the build status
[04:32:00.227] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:00.227] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:185:0) after 1m0s
[04:33:00.650] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:185:0)
[04:33:00.650] Timer tick, asking Buildbucket for the build status
[04:33:00.916] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:00.916] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:186:0) after 1m0s
[04:34:01.147] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:186:0)
[04:34:01.147] Timer tick, asking Buildbucket for the build status
[04:34:01.557] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:01.557] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:187:0) after 1m0s
[04:35:02.456] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:187:0)
[04:35:02.456] Timer tick, asking Buildbucket for the build status
[04:35:02.597] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:02.597] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:188:0) after 1m0s
[04:36:02.701] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:188:0)
[04:36:02.701] Timer tick, asking Buildbucket for the build status
[04:36:03.015] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:03.015] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:189:0) after 1m0s
[04:37:03.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:189:0)
[04:37:03.067] Timer tick, asking Buildbucket for the build status
[04:37:03.201] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:03.201] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:190:0) after 1m0s
[04:38:03.256] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:190:0)
[04:38:03.256] Timer tick, asking Buildbucket for the build status
[04:38:03.352] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:03.352] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:191:0) after 1m0s
[04:39:03.406] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:191:0)
[04:39:03.406] Timer tick, asking Buildbucket for the build status
[04:39:03.633] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:03.633] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:192:0) after 1m0s
[04:40:03.703] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:192:0)
[04:40:03.703] Timer tick, asking Buildbucket for the build status
[04:40:03.847] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:03.847] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:193:0) after 1m0s
[04:41:03.868] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:193:0)
[04:41:03.868] Timer tick, asking Buildbucket for the build status
[04:41:04.157] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:04.157] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:194:0) after 1m0s
[04:42:04.191] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:194:0)
[04:42:04.191] Timer tick, asking Buildbucket for the build status
[04:42:04.407] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:04.407] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:195:0) after 1m0s
[04:43:04.445] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:195:0)
[04:43:04.445] Timer tick, asking Buildbucket for the build status
[04:43:04.572] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:04.572] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:196:0) after 1m0s
[04:44:04.740] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:196:0)
[04:44:04.740] Timer tick, asking Buildbucket for the build status
[04:44:04.886] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:04.886] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:197:0) after 1m0s
[04:45:05.374] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:197:0)
[04:45:05.374] Timer tick, asking Buildbucket for the build status
[04:45:05.749] Build 8860072593040631504: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:05.749] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9025502655472732144:198:0) after 1m0s
[04:45:35.082] Received PubSub notification, asking Buildbucket for the build status
[04:45:35.174] Build 8860072593040631504: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:45:35.174] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608785134276494",
"created_by": "project:chromium",
"created_ts": "1608773348730874",
"id": "8860072593040631504",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39b939f13f6b15d749b41e0fa1744c814f209962\"}}",
"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\": \"39b939f13f6b15d749b41e0fa1744c814f209962\", \"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-6-co59\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1180, \"got_angle_revision\": \"e16ff968e8a40413cd274975e8681f213c0e536c\", \"got_dawn_revision\": \"a4fb42ac92cc4fc391bc48b2d66f72065b3a1b69\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"39b939f13f6b15d749b41e0fa1744c814f209962\", \"got_revision_cp\": \"refs/heads/master@{#839221}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"711cf5ad4a12abe0f6b50058cb2248d7fb80374e\", \"got_v8_revision_cp\": \"refs/heads/8.9.187@{#1}\", \"got_webrtc_revision\": \"fec83cc8602580ce1c078f7cd4e7f4d2be4ac850\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32877}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"39b939f13f6b15d749b41e0fa1744c814f209962\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_a69e6b6949245e742d9a022200655bac5124ee86184973d811510d142a3a53fc_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-6-co59\"], \"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\": [\"5657-0417a3f\"], \"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": "1608773402178263",
"status": "COMPLETED",
"status_changed_ts": "1608785134548829",
"tags": [
"build_address:luci.chromium.ci/win32-official/1180",
"builder:win32-official",
"buildset:commit/git/39b939f13f6b15d749b41e0fa1744c814f209962",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/39b939f13f6b15d749b41e0fa1744c814f209962",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025502655472732144",
"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/8860072593040631504/+/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:50ab2ebaba3a9510",
"user_agent:luci-scheduler"
],
"updated_ts": "1608785134569150",
"url": "https://ci.chromium.org/b/8860072593040631504",
"utcnow_ts": "1608785135158137"
}
}
[04:45:35.174] Invocation finished in 3h16m28.147492921s with status SUCCEEDED