[04:30:56.995] New invocation is queued and will start shortly
[04:30:58.267] Starting the invocation (attempt 1)
[04:30:58.303] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:30:58.304] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:30:58.304] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022048530838988656",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"342fc315c4dfc90fdc5996612be005dcb5a7ad32\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9022048530838988656",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/342fc315c4dfc90fdc5996612be005dcb5a7ad32",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/342fc315c4dfc90fdc5996612be005dcb5a7ad32",
"gitiles_ref:refs/heads/master"
]
}
[04:30:58.947] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1612067458392621",
"id": "8856618468706957456",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"342fc315c4dfc90fdc5996612be005dcb5a7ad32\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612067458740192",
"tags": [
"build_address:luci.chromium.ci/win-official/1501",
"builder:win-official",
"buildset:commit/git/342fc315c4dfc90fdc5996612be005dcb5a7ad32",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/342fc315c4dfc90fdc5996612be005dcb5a7ad32",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022048530838988656",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856618468706957456/+/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": "1612067458740369",
"url": "https://ci.chromium.org/b/8856618468706957456",
"utcnow_ts": "1612067458938139"
}
}
[04:30:58.947] Task URL: https://ci.chromium.org/b/8856618468706957456
[04:30:58.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:2:0) after 1m0s
[04:31:37.663] Received PubSub notification, asking Buildbucket for the build status
[04:31:37.839] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:58.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:2:0)
[04:31:58.964] Timer tick, asking Buildbucket for the build status
[04:31:59.027] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:59.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:4:0) after 1m0s
[04:32:59.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:4:0)
[04:32:59.077] Timer tick, asking Buildbucket for the build status
[04:32:59.150] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:59.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:5:0) after 1m0s
[04:33:59.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:5:0)
[04:33:59.171] Timer tick, asking Buildbucket for the build status
[04:33:59.256] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:59.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:6:0) after 1m0s
[04:34:59.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:6:0)
[04:34:59.278] Timer tick, asking Buildbucket for the build status
[04:34:59.437] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:59.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:7:0) after 1m0s
[04:35:59.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:7:0)
[04:35:59.466] Timer tick, asking Buildbucket for the build status
[04:35:59.524] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:59.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:8:0) after 1m0s
[04:36:59.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:8:0)
[04:36:59.567] Timer tick, asking Buildbucket for the build status
[04:36:59.676] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:59.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:9:0) after 1m0s
[04:37:59.733] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:9:0)
[04:37:59.733] Timer tick, asking Buildbucket for the build status
[04:37:59.849] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:59.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:10:0) after 1m0s
[04:38:59.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:10:0)
[04:38:59.899] Timer tick, asking Buildbucket for the build status
[04:39:00.172] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:00.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:11:0) after 1m0s
[04:40:00.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:11:0)
[04:40:00.154] Timer tick, asking Buildbucket for the build status
[04:40:00.307] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:00.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:12:0) after 1m0s
[04:41:00.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:12:0)
[04:41:00.357] Timer tick, asking Buildbucket for the build status
[04:41:00.452] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:00.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:13:0) after 1m0s
[04:42:00.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:13:0)
[04:42:00.547] Timer tick, asking Buildbucket for the build status
[04:42:00.754] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:00.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:14:0) after 1m0s
[04:43:01.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:14:0)
[04:43:01.159] Timer tick, asking Buildbucket for the build status
[04:43:01.516] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:01.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:15:0) after 1m0s
[04:44:01.705] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:15:0)
[04:44:01.705] Timer tick, asking Buildbucket for the build status
[04:44:01.872] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:01.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:16:0) after 1m0s
[04:45:01.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:16:0)
[04:45:01.891] Timer tick, asking Buildbucket for the build status
[04:45:01.980] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:01.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:17:0) after 1m0s
[04:46:02.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:17:0)
[04:46:02.021] Timer tick, asking Buildbucket for the build status
[04:46:02.319] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:02.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:18:0) after 1m0s
[04:47:02.375] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:18:0)
[04:47:02.376] Timer tick, asking Buildbucket for the build status
[04:47:02.466] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:02.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:19:0) after 1m0s
[04:48:02.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:19:0)
[04:48:02.555] Timer tick, asking Buildbucket for the build status
[04:48:02.759] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:02.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:20:0) after 1m0s
[04:49:02.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:20:0)
[04:49:02.761] Timer tick, asking Buildbucket for the build status
[04:49:02.956] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:02.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:21:0) after 1m0s
[04:50:03.010] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:21:0)
[04:50:03.010] Timer tick, asking Buildbucket for the build status
[04:50:03.131] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:03.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:22:0) after 1m0s
[04:51:03.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:22:0)
[04:51:03.157] Timer tick, asking Buildbucket for the build status
[04:51:03.607] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:03.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:23:0) after 1m0s
[04:52:03.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:23:0)
[04:52:03.724] Timer tick, asking Buildbucket for the build status
[04:52:03.860] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:03.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:24:0) after 1m0s
[04:53:03.903] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:24:0)
[04:53:03.903] Timer tick, asking Buildbucket for the build status
[04:53:03.963] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:03.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:25:0) after 1m0s
[04:54:03.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:25:0)
[04:54:03.985] Timer tick, asking Buildbucket for the build status
[04:54:04.053] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:04.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:26:0) after 1m0s
[04:55:04.062] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:26:0)
[04:55:04.062] Timer tick, asking Buildbucket for the build status
[04:55:04.121] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:04.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:27:0) after 1m0s
[04:56:04.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:27:0)
[04:56:04.176] Timer tick, asking Buildbucket for the build status
[04:56:04.354] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:04.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:28:0) after 1m0s
[04:57:04.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:28:0)
[04:57:04.335] Timer tick, asking Buildbucket for the build status
[04:57:04.399] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:04.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:29:0) after 1m0s
[04:58:04.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:29:0)
[04:58:04.492] Timer tick, asking Buildbucket for the build status
[04:58:04.604] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:04.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:30:0) after 1m0s
[04:59:04.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:30:0)
[04:59:04.685] Timer tick, asking Buildbucket for the build status
[04:59:04.819] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:04.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:31:0) after 1m0s
[05:00:04.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:31:0)
[05:00:04.842] Timer tick, asking Buildbucket for the build status
[05:00:04.924] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:04.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:32:0) after 1m0s
[05:01:05.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:32:0)
[05:01:05.366] Timer tick, asking Buildbucket for the build status
[05:01:05.511] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:05.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:33:0) after 1m0s
[05:02:05.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:33:0)
[05:02:05.687] Timer tick, asking Buildbucket for the build status
[05:02:05.775] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:05.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:34:0) after 1m0s
[05:03:05.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:34:0)
[05:03:05.855] Timer tick, asking Buildbucket for the build status
[05:03:05.939] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:05.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:35:0) after 1m0s
[05:04:06.048] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:35:0)
[05:04:06.048] Timer tick, asking Buildbucket for the build status
[05:04:06.107] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:06.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:36:0) after 1m0s
[05:05:06.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:36:0)
[05:05:06.130] Timer tick, asking Buildbucket for the build status
[05:05:06.565] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:06.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:37:0) after 1m0s
[05:06:06.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:37:0)
[05:06:06.692] Timer tick, asking Buildbucket for the build status
[05:06:07.622] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:07.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:38:0) after 1m0s
[05:07:07.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:38:0)
[05:07:07.726] Timer tick, asking Buildbucket for the build status
[05:07:07.824] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:07.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:39:0) after 1m0s
[05:08:07.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:39:0)
[05:08:07.885] Timer tick, asking Buildbucket for the build status
[05:08:07.953] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:07.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:40:0) after 1m0s
[05:09:08.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:40:0)
[05:09:08.084] Timer tick, asking Buildbucket for the build status
[05:09:08.216] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:08.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:41:0) after 1m0s
[05:10:08.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:41:0)
[05:10:08.230] Timer tick, asking Buildbucket for the build status
[05:10:08.456] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:08.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:42:0) after 1m0s
[05:11:08.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:42:0)
[05:11:08.475] Timer tick, asking Buildbucket for the build status
[05:11:08.569] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:08.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:43:0) after 1m0s
[05:12:08.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:43:0)
[05:12:08.594] Timer tick, asking Buildbucket for the build status
[05:12:08.826] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:08.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:44:0) after 1m0s
[05:13:08.845] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:44:0)
[05:13:08.845] Timer tick, asking Buildbucket for the build status
[05:13:09.113] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:09.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:45:0) after 1m0s
[05:14:09.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:45:0)
[05:14:09.207] Timer tick, asking Buildbucket for the build status
[05:14:09.399] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:09.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:46:0) after 1m0s
[05:15:09.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:46:0)
[05:15:09.460] Timer tick, asking Buildbucket for the build status
[05:15:09.519] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:09.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:47:0) after 1m0s
[05:16:09.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:47:0)
[05:16:09.575] Timer tick, asking Buildbucket for the build status
[05:16:09.689] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:09.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:48:0) after 1m0s
[05:17:09.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:48:0)
[05:17:09.729] Timer tick, asking Buildbucket for the build status
[05:17:09.791] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:09.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:49:0) after 1m0s
[05:18:09.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:49:0)
[05:18:09.912] Timer tick, asking Buildbucket for the build status
[05:18:09.976] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:09.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:50:0) after 1m0s
[05:19:10.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:50:0)
[05:19:10.003] Timer tick, asking Buildbucket for the build status
[05:19:10.156] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:10.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:51:0) after 1m0s
[05:20:10.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:51:0)
[05:20:10.179] Timer tick, asking Buildbucket for the build status
[05:20:10.238] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:10.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:52:0) after 1m0s
[05:21:10.343] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:52:0)
[05:21:10.343] Timer tick, asking Buildbucket for the build status
[05:21:10.452] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:10.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:53:0) after 1m0s
[05:22:10.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:53:0)
[05:22:10.509] Timer tick, asking Buildbucket for the build status
[05:22:10.884] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:10.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:54:0) after 1m0s
[05:23:10.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:54:0)
[05:23:10.913] Timer tick, asking Buildbucket for the build status
[05:23:11.131] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:11.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:55:0) after 1m0s
[05:24:11.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:55:0)
[05:24:11.172] Timer tick, asking Buildbucket for the build status
[05:24:11.251] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:11.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:56:0) after 1m0s
[05:25:11.271] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:56:0)
[05:25:11.271] Timer tick, asking Buildbucket for the build status
[05:25:11.557] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:11.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:57:0) after 1m0s
[05:26:11.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:57:0)
[05:26:11.724] Timer tick, asking Buildbucket for the build status
[05:26:11.825] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:11.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:58:0) after 1m0s
[05:27:11.931] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:58:0)
[05:27:11.931] Timer tick, asking Buildbucket for the build status
[05:27:12.291] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:12.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:59:0) after 1m0s
[05:28:12.408] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:59:0)
[05:28:12.408] Timer tick, asking Buildbucket for the build status
[05:28:12.469] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:12.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:60:0) after 1m0s
[05:29:12.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:60:0)
[05:29:12.518] Timer tick, asking Buildbucket for the build status
[05:29:12.595] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:12.595] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:61:0) after 1m0s
[05:30:12.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:61:0)
[05:30:12.633] Timer tick, asking Buildbucket for the build status
[05:30:12.695] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:12.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:62:0) after 1m0s
[05:31:12.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:62:0)
[05:31:12.788] Timer tick, asking Buildbucket for the build status
[05:31:12.900] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:12.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:63:0) after 1m0s
[05:32:13.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:63:0)
[05:32:13.027] Timer tick, asking Buildbucket for the build status
[05:32:13.093] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:13.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:64:0) after 1m0s
[05:33:13.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:64:0)
[05:33:13.117] Timer tick, asking Buildbucket for the build status
[05:33:13.214] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:13.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:65:0) after 1m0s
[05:34:13.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:65:0)
[05:34:13.246] Timer tick, asking Buildbucket for the build status
[05:34:13.310] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:13.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:66:0) after 1m0s
[05:35:13.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:66:0)
[05:35:13.362] Timer tick, asking Buildbucket for the build status
[05:35:13.429] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:13.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:67:0) after 1m0s
[05:36:13.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:67:0)
[05:36:13.498] Timer tick, asking Buildbucket for the build status
[05:36:13.611] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:13.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:68:0) after 1m0s
[05:37:13.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:68:0)
[05:37:13.634] Timer tick, asking Buildbucket for the build status
[05:37:13.744] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:13.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:69:0) after 1m0s
[05:38:13.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:69:0)
[05:38:13.742] Timer tick, asking Buildbucket for the build status
[05:38:13.798] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:13.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:70:0) after 1m0s
[05:39:13.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:70:0)
[05:39:13.853] Timer tick, asking Buildbucket for the build status
[05:39:13.906] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:13.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:71:0) after 1m0s
[05:40:14.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:71:0)
[05:40:14.052] Timer tick, asking Buildbucket for the build status
[05:40:14.118] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:14.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:72:0) after 1m0s
[05:41:14.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:72:0)
[05:41:14.253] Timer tick, asking Buildbucket for the build status
[05:41:14.649] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:14.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:73:0) after 1m0s
[05:42:14.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:73:0)
[05:42:14.720] Timer tick, asking Buildbucket for the build status
[05:42:14.777] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:14.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:74:0) after 1m0s
[05:43:14.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:74:0)
[05:43:14.828] Timer tick, asking Buildbucket for the build status
[05:43:14.880] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:14.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:75:0) after 1m0s
[05:44:14.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:75:0)
[05:44:14.858] Timer tick, asking Buildbucket for the build status
[05:44:14.921] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:14.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:76:0) after 1m0s
[05:45:14.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:76:0)
[05:45:14.943] Timer tick, asking Buildbucket for the build status
[05:45:15.007] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:15.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:77:0) after 1m0s
[05:46:15.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:77:0)
[05:46:15.059] Timer tick, asking Buildbucket for the build status
[05:46:15.156] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:15.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:78:0) after 1m0s
[05:47:15.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:78:0)
[05:47:15.169] Timer tick, asking Buildbucket for the build status
[05:47:15.538] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:15.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:79:0) after 1m0s
[05:48:15.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:79:0)
[05:48:15.631] Timer tick, asking Buildbucket for the build status
[05:48:15.895] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:15.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:80:0) after 1m0s
[05:49:15.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:80:0)
[05:49:15.983] Timer tick, asking Buildbucket for the build status
[05:49:16.049] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:16.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:81:0) after 1m0s
[05:50:16.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:81:0)
[05:50:16.075] Timer tick, asking Buildbucket for the build status
[05:50:16.347] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:16.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:82:0) after 1m0s
[05:51:16.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:82:0)
[05:51:16.372] Timer tick, asking Buildbucket for the build status
[05:51:16.470] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:16.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:83:0) after 1m0s
[05:52:16.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:83:0)
[05:52:16.491] Timer tick, asking Buildbucket for the build status
[05:52:16.554] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:16.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:84:0) after 1m0s
[05:53:16.579] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:84:0)
[05:53:16.579] Timer tick, asking Buildbucket for the build status
[05:53:16.752] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:16.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:85:0) after 1m0s
[05:54:16.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:85:0)
[05:54:16.728] Timer tick, asking Buildbucket for the build status
[05:54:16.831] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:16.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:86:0) after 1m0s
[05:55:16.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:86:0)
[05:55:16.827] Timer tick, asking Buildbucket for the build status
[05:55:16.888] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:16.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:87:0) after 1m0s
[05:56:16.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:87:0)
[05:56:16.871] Timer tick, asking Buildbucket for the build status
[05:56:16.971] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:16.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:88:0) after 1m0s
[05:57:17.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:88:0)
[05:57:17.059] Timer tick, asking Buildbucket for the build status
[05:57:17.234] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:17.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:89:0) after 1m0s
[05:58:17.289] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:89:0)
[05:58:17.289] Timer tick, asking Buildbucket for the build status
[05:58:17.341] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:17.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:90:0) after 1m0s
[05:59:17.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:90:0)
[05:59:17.405] Timer tick, asking Buildbucket for the build status
[05:59:17.522] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:17.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:91:0) after 1m0s
[06:00:17.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:91:0)
[06:00:17.587] Timer tick, asking Buildbucket for the build status
[06:00:17.674] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:17.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:92:0) after 1m0s
[06:01:25.606] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:92:0)
[06:01:25.606] Timer tick, asking Buildbucket for the build status
[06:01:25.726] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:25.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:93:0) after 1m0s
[06:02:25.986] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:93:0)
[06:02:25.986] Timer tick, asking Buildbucket for the build status
[06:02:26.202] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:26.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:94:0) after 1m0s
[06:03:26.640] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:94:0)
[06:03:26.640] Timer tick, asking Buildbucket for the build status
[06:03:26.702] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:26.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:95:0) after 1m0s
[06:04:26.905] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:95:0)
[06:04:26.905] Timer tick, asking Buildbucket for the build status
[06:04:27.106] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:27.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:96:0) after 1m0s
[06:05:27.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:96:0)
[06:05:27.437] Timer tick, asking Buildbucket for the build status
[06:05:27.498] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:27.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:97:0) after 1m0s
[06:06:27.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:97:0)
[06:06:27.597] Timer tick, asking Buildbucket for the build status
[06:06:27.727] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:27.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:98:0) after 1m0s
[06:07:27.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:98:0)
[06:07:27.867] Timer tick, asking Buildbucket for the build status
[06:07:28.211] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:28.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:99:0) after 1m0s
[06:08:28.489] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:99:0)
[06:08:28.489] Timer tick, asking Buildbucket for the build status
[06:08:28.545] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:28.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:100:0) after 1m0s
[06:09:28.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:100:0)
[06:09:28.615] Timer tick, asking Buildbucket for the build status
[06:09:28.679] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:28.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:101:0) after 1m0s
[06:10:28.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:101:0)
[06:10:28.817] Timer tick, asking Buildbucket for the build status
[06:10:28.883] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:28.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:102:0) after 1m0s
[06:11:30.706] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:102:0)
[06:11:30.706] Timer tick, asking Buildbucket for the build status
[06:11:31.259] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:31.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:103:0) after 1m0s
[06:12:31.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:103:0)
[06:12:31.469] Timer tick, asking Buildbucket for the build status
[06:12:31.591] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:31.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:104:0) after 1m0s
[06:13:36.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:104:0)
[06:13:36.590] Timer tick, asking Buildbucket for the build status
[06:13:36.650] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:36.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:105:0) after 1m0s
[06:14:36.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:105:0)
[06:14:36.671] Timer tick, asking Buildbucket for the build status
[06:14:36.920] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:36.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:106:0) after 1m0s
[06:15:36.968] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:106:0)
[06:15:36.969] Timer tick, asking Buildbucket for the build status
[06:15:37.130] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:37.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:107:0) after 1m0s
[06:16:37.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:107:0)
[06:16:37.153] Timer tick, asking Buildbucket for the build status
[06:16:37.918] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:37.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:108:0) after 1m0s
[06:17:38.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:108:0)
[06:17:38.013] Timer tick, asking Buildbucket for the build status
[06:17:38.088] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:38.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:109:0) after 1m0s
[06:18:38.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:109:0)
[06:18:38.155] Timer tick, asking Buildbucket for the build status
[06:18:38.402] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:38.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:110:0) after 1m0s
[06:19:38.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:110:0)
[06:19:38.516] Timer tick, asking Buildbucket for the build status
[06:19:38.576] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:38.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:111:0) after 1m0s
[06:20:38.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:111:0)
[06:20:38.608] Timer tick, asking Buildbucket for the build status
[06:20:38.673] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:38.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:112:0) after 1m0s
[06:21:38.860] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:112:0)
[06:21:38.860] Timer tick, asking Buildbucket for the build status
[06:21:38.975] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:38.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:113:0) after 1m0s
[06:22:39.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:113:0)
[06:22:39.144] Timer tick, asking Buildbucket for the build status
[06:22:39.217] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:39.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:114:0) after 1m0s
[06:23:39.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:114:0)
[06:23:39.287] Timer tick, asking Buildbucket for the build status
[06:23:39.355] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:39.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:115:0) after 1m0s
[06:24:39.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:115:0)
[06:24:39.465] Timer tick, asking Buildbucket for the build status
[06:24:39.518] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:39.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:116:0) after 1m0s
[06:25:39.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:116:0)
[06:25:39.572] Timer tick, asking Buildbucket for the build status
[06:25:39.936] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:39.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:117:0) after 1m0s
[06:26:40.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:117:0)
[06:26:40.027] Timer tick, asking Buildbucket for the build status
[06:26:40.245] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:40.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:118:0) after 1m0s
[06:27:40.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:118:0)
[06:27:40.432] Timer tick, asking Buildbucket for the build status
[06:27:40.624] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:40.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:119:0) after 1m0s
[06:28:40.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:119:0)
[06:28:40.685] Timer tick, asking Buildbucket for the build status
[06:28:40.773] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:40.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:120:0) after 1m0s
[06:29:40.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:120:0)
[06:29:40.832] Timer tick, asking Buildbucket for the build status
[06:29:40.897] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:40.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:121:0) after 1m0s
[06:30:40.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:121:0)
[06:30:40.899] Timer tick, asking Buildbucket for the build status
[06:30:41.001] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:41.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:122:0) after 1m0s
[06:31:41.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:122:0)
[06:31:41.126] Timer tick, asking Buildbucket for the build status
[06:31:41.342] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:41.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:123:0) after 1m0s
[06:32:41.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:123:0)
[06:32:41.332] Timer tick, asking Buildbucket for the build status
[06:32:41.396] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:41.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:124:0) after 1m0s
[06:33:41.469] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:124:0)
[06:33:41.469] Timer tick, asking Buildbucket for the build status
[06:33:41.721] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:41.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:125:0) after 1m0s
[06:34:41.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:125:0)
[06:34:41.803] Timer tick, asking Buildbucket for the build status
[06:34:41.886] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:41.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:126:0) after 1m0s
[06:35:41.942] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:126:0)
[06:35:41.942] Timer tick, asking Buildbucket for the build status
[06:35:42.147] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:42.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:127:0) after 1m0s
[06:36:42.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:127:0)
[06:36:42.208] Timer tick, asking Buildbucket for the build status
[06:36:42.351] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:42.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:128:0) after 1m0s
[06:37:42.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:128:0)
[06:37:42.425] Timer tick, asking Buildbucket for the build status
[06:37:42.495] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:42.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:129:0) after 1m0s
[06:38:42.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:129:0)
[06:38:42.518] Timer tick, asking Buildbucket for the build status
[06:38:42.589] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:42.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:130:0) after 1m0s
[06:39:42.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:130:0)
[06:39:42.629] Timer tick, asking Buildbucket for the build status
[06:39:42.701] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:42.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:131:0) after 1m0s
[06:40:42.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:131:0)
[06:40:42.732] Timer tick, asking Buildbucket for the build status
[06:40:42.792] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:42.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:132:0) after 1m0s
[06:41:42.822] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:132:0)
[06:41:42.823] Timer tick, asking Buildbucket for the build status
[06:41:42.948] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:42.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:133:0) after 1m0s
[06:42:42.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:133:0)
[06:42:42.971] Timer tick, asking Buildbucket for the build status
[06:42:43.132] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:43.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:134:0) after 1m0s
[06:43:43.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:134:0)
[06:43:43.172] Timer tick, asking Buildbucket for the build status
[06:43:43.414] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:43.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:135:0) after 1m0s
[06:44:43.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:135:0)
[06:44:43.388] Timer tick, asking Buildbucket for the build status
[06:44:43.452] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:43.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:136:0) after 1m0s
[06:45:43.480] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:136:0)
[06:45:43.480] Timer tick, asking Buildbucket for the build status
[06:45:43.539] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:43.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:137:0) after 1m0s
[06:46:43.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:137:0)
[06:46:43.600] Timer tick, asking Buildbucket for the build status
[06:46:43.793] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:43.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:138:0) after 1m0s
[06:47:43.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:138:0)
[06:47:43.819] Timer tick, asking Buildbucket for the build status
[06:47:43.890] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:43.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:139:0) after 1m0s
[06:48:43.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:139:0)
[06:48:43.913] Timer tick, asking Buildbucket for the build status
[06:48:44.093] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:44.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:140:0) after 1m0s
[06:49:44.156] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:140:0)
[06:49:44.156] Timer tick, asking Buildbucket for the build status
[06:49:44.242] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:44.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:141:0) after 1m0s
[06:50:44.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:141:0)
[06:50:44.388] Timer tick, asking Buildbucket for the build status
[06:50:44.730] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:44.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:142:0) after 1m0s
[06:51:44.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:142:0)
[06:51:44.774] Timer tick, asking Buildbucket for the build status
[06:51:45.200] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:45.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:143:0) after 1m0s
[06:52:45.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:143:0)
[06:52:45.266] Timer tick, asking Buildbucket for the build status
[06:52:45.358] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:45.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:144:0) after 1m0s
[06:53:45.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:144:0)
[06:53:45.364] Timer tick, asking Buildbucket for the build status
[06:53:45.430] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:45.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:145:0) after 1m0s
[06:54:45.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:145:0)
[06:54:45.496] Timer tick, asking Buildbucket for the build status
[06:54:45.565] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:45.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:146:0) after 1m0s
[06:55:45.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:146:0)
[06:55:45.613] Timer tick, asking Buildbucket for the build status
[06:55:45.671] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:45.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:147:0) after 1m0s
[06:56:45.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:147:0)
[06:56:45.710] Timer tick, asking Buildbucket for the build status
[06:56:45.953] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:45.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:148:0) after 1m0s
[06:57:45.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:148:0)
[06:57:45.970] Timer tick, asking Buildbucket for the build status
[06:57:46.036] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:46.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:149:0) after 1m0s
[06:58:46.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:149:0)
[06:58:46.106] Timer tick, asking Buildbucket for the build status
[06:58:46.169] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:46.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:150:0) after 1m0s
[06:59:46.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:150:0)
[06:59:46.243] Timer tick, asking Buildbucket for the build status
[06:59:46.308] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:46.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:151:0) after 1m0s
[07:00:46.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:151:0)
[07:00:46.340] Timer tick, asking Buildbucket for the build status
[07:00:46.528] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:46.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:152:0) after 1m0s
[07:01:46.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:152:0)
[07:01:46.618] Timer tick, asking Buildbucket for the build status
[07:01:46.905] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:46.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:153:0) after 1m0s
[07:02:46.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:153:0)
[07:02:46.949] Timer tick, asking Buildbucket for the build status
[07:02:47.009] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:47.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:154:0) after 1m0s
[07:03:47.031] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:154:0)
[07:03:47.031] Timer tick, asking Buildbucket for the build status
[07:03:47.092] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:47.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:155:0) after 1m0s
[07:04:47.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:155:0)
[07:04:47.160] Timer tick, asking Buildbucket for the build status
[07:04:47.238] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:47.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:156:0) after 1m0s
[07:05:47.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:156:0)
[07:05:47.270] Timer tick, asking Buildbucket for the build status
[07:05:47.533] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:47.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:157:0) after 1m0s
[07:06:47.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:157:0)
[07:06:47.583] Timer tick, asking Buildbucket for the build status
[07:06:47.745] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:47.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:158:0) after 1m0s
[07:07:47.841] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:158:0)
[07:07:47.841] Timer tick, asking Buildbucket for the build status
[07:07:47.947] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:47.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:159:0) after 1m0s
[07:08:48.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:159:0)
[07:08:48.052] Timer tick, asking Buildbucket for the build status
[07:08:48.206] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:48.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:160:0) after 1m0s
[07:09:48.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:160:0)
[07:09:48.245] Timer tick, asking Buildbucket for the build status
[07:09:48.372] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:48.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:161:0) after 1m0s
[07:10:48.433] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:161:0)
[07:10:48.433] Timer tick, asking Buildbucket for the build status
[07:10:48.494] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:48.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:162:0) after 1m0s
[07:11:48.595] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:162:0)
[07:11:48.595] Timer tick, asking Buildbucket for the build status
[07:11:48.910] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:48.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:163:0) after 1m0s
[07:12:48.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:163:0)
[07:12:48.959] Timer tick, asking Buildbucket for the build status
[07:12:49.048] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:49.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:164:0) after 1m0s
[07:13:49.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:164:0)
[07:13:49.281] Timer tick, asking Buildbucket for the build status
[07:13:49.602] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:49.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:165:0) after 1m0s
[07:14:49.720] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:165:0)
[07:14:49.720] Timer tick, asking Buildbucket for the build status
[07:14:49.805] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:49.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:166:0) after 1m0s
[07:15:49.874] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:166:0)
[07:15:49.874] Timer tick, asking Buildbucket for the build status
[07:15:49.986] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:49.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:167:0) after 1m0s
[07:16:50.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:167:0)
[07:16:50.006] Timer tick, asking Buildbucket for the build status
[07:16:50.229] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:50.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:168:0) after 1m0s
[07:17:50.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:168:0)
[07:17:50.390] Timer tick, asking Buildbucket for the build status
[07:17:50.594] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:50.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:169:0) after 1m0s
[07:18:50.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:169:0)
[07:18:50.625] Timer tick, asking Buildbucket for the build status
[07:18:50.684] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:50.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:170:0) after 1m0s
[07:19:50.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:170:0)
[07:19:50.726] Timer tick, asking Buildbucket for the build status
[07:19:51.218] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:51.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:171:0) after 1m0s
[07:20:51.304] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:171:0)
[07:20:51.304] Timer tick, asking Buildbucket for the build status
[07:20:51.424] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:51.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:172:0) after 1m0s
[07:21:51.446] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:172:0)
[07:21:51.446] Timer tick, asking Buildbucket for the build status
[07:21:51.818] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:51.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:173:0) after 1m0s
[07:22:51.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:173:0)
[07:22:51.884] Timer tick, asking Buildbucket for the build status
[07:22:51.959] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:51.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:174:0) after 1m0s
[07:23:52.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:174:0)
[07:23:52.030] Timer tick, asking Buildbucket for the build status
[07:23:52.102] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:52.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:175:0) after 1m0s
[07:24:52.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:175:0)
[07:24:52.164] Timer tick, asking Buildbucket for the build status
[07:24:52.384] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:52.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:176:0) after 1m0s
[07:25:52.401] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:176:0)
[07:25:52.401] Timer tick, asking Buildbucket for the build status
[07:25:52.478] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:52.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:177:0) after 1m0s
[07:26:52.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:177:0)
[07:26:52.550] Timer tick, asking Buildbucket for the build status
[07:26:52.857] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:52.857] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:178:0) after 1m0s
[07:27:52.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:178:0)
[07:27:52.898] Timer tick, asking Buildbucket for the build status
[07:27:53.109] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:53.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:179:0) after 1m0s
[07:28:53.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:179:0)
[07:28:53.149] Timer tick, asking Buildbucket for the build status
[07:28:53.254] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:53.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:180:0) after 1m0s
[07:29:53.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:180:0)
[07:29:53.296] Timer tick, asking Buildbucket for the build status
[07:29:53.399] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:53.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:181:0) after 1m0s
[07:30:53.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:181:0)
[07:30:53.424] Timer tick, asking Buildbucket for the build status
[07:30:53.721] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:53.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:182:0) after 1m0s
[07:31:53.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:182:0)
[07:31:53.785] Timer tick, asking Buildbucket for the build status
[07:31:53.946] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:53.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:183:0) after 1m0s
[07:32:54.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:183:0)
[07:32:54.005] Timer tick, asking Buildbucket for the build status
[07:32:54.123] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:54.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:184:0) after 1m0s
[07:33:54.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:184:0)
[07:33:54.132] Timer tick, asking Buildbucket for the build status
[07:33:54.203] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:54.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:185:0) after 1m0s
[07:34:54.267] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:185:0)
[07:34:54.267] Timer tick, asking Buildbucket for the build status
[07:34:54.349] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:54.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:186:0) after 1m0s
[07:35:54.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:186:0)
[07:35:54.392] Timer tick, asking Buildbucket for the build status
[07:35:54.454] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:54.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:187:0) after 1m0s
[07:36:54.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:187:0)
[07:36:54.505] Timer tick, asking Buildbucket for the build status
[07:36:54.916] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:54.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:188:0) after 1m0s
[07:37:55.014] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:188:0)
[07:37:55.014] Timer tick, asking Buildbucket for the build status
[07:37:55.097] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:55.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:189:0) after 1m0s
[07:38:55.164] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:189:0)
[07:38:55.164] Timer tick, asking Buildbucket for the build status
[07:38:55.431] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:55.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:190:0) after 1m0s
[07:39:55.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:190:0)
[07:39:55.466] Timer tick, asking Buildbucket for the build status
[07:39:55.701] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:55.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:191:0) after 1m0s
[07:40:55.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:191:0)
[07:40:55.735] Timer tick, asking Buildbucket for the build status
[07:40:55.799] Build 8856618468706957456: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:55.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9022048530838988656:192:0) after 1m0s
[07:41:54.870] Received PubSub notification, asking Buildbucket for the build status
[07:41:54.949] Build 8856618468706957456: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:41:54.949] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1612078914366057",
"created_by": "project:chromium",
"created_ts": "1612067458392621",
"id": "8856618468706957456",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"342fc315c4dfc90fdc5996612be005dcb5a7ad32\"}}",
"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\": \"342fc315c4dfc90fdc5996612be005dcb5a7ad32\", \"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-7t34\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1501, \"got_angle_revision\": \"eae262e7fa3d375d8a62e95ec4326d59040704de\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"342fc315c4dfc90fdc5996612be005dcb5a7ad32\", \"got_revision_cp\": \"refs/heads/master@{#848921}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8b4cf1c2491d56cf9eda153b5455e4b295732b7c\", \"got_v8_revision\": \"cbbe339c1ca9754b3123fe2bdb83c0f58ee5d728\", \"got_v8_revision_cp\": \"refs/heads/9.0.101@{#1}\", \"got_webrtc_revision\": \"5ac4212bec6faf20250d4632d0ced86c774b9e3e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33114}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"342fc315c4dfc90fdc5996612be005dcb5a7ad32\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-6-7t34\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5673-2413400\"], \"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": "1612067497218422",
"status": "COMPLETED",
"status_changed_ts": "1612078914660242",
"tags": [
"build_address:luci.chromium.ci/win-official/1501",
"builder:win-official",
"buildset:commit/git/342fc315c4dfc90fdc5996612be005dcb5a7ad32",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/342fc315c4dfc90fdc5996612be005dcb5a7ad32",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022048530838988656",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856618468706957456/+/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:516f86d818799010",
"user_agent:luci-scheduler"
],
"updated_ts": "1612078914664564",
"url": "https://ci.chromium.org/b/8856618468706957456",
"utcnow_ts": "1612078914939799"
}
}
[07:41:54.949] Invocation finished in 3h10m57.97299301s with status SUCCEEDED