[10:35:20.534] New invocation is queued and will start shortly
[10:35:21.691] Starting the invocation (attempt 1)
[10:35:21.726] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:35:21.726] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:35:21.726] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023112768849592688",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d9bf8f79861573a7512e74031f08ac28834c6ded\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9023112768849592688",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/d9bf8f79861573a7512e74031f08ac28834c6ded",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d9bf8f79861573a7512e74031f08ac28834c6ded",
"gitiles_ref:refs/heads/master"
]
}
[10:35:22.517] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611052521804908",
"id": "8857682706855041056",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d9bf8f79861573a7512e74031f08ac28834c6ded\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611052522293891",
"tags": [
"build_address:luci.chromium.ci/win-official/1414",
"builder:win-official",
"buildset:commit/git/d9bf8f79861573a7512e74031f08ac28834c6ded",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d9bf8f79861573a7512e74031f08ac28834c6ded",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023112768849592688",
"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/8857682706855041056/+/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": "1611052522293996",
"url": "https://ci.chromium.org/b/8857682706855041056",
"utcnow_ts": "1611052522510338"
}
}
[10:35:22.517] Task URL: https://ci.chromium.org/b/8857682706855041056
[10:35:22.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:2:0) after 1m0s
[10:36:20.356] Received PubSub notification, asking Buildbucket for the build status
[10:36:20.536] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:22.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:2:0)
[10:36:22.543] Timer tick, asking Buildbucket for the build status
[10:36:22.617] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:22.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:4:0) after 1m0s
[10:37:22.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:4:0)
[10:37:22.604] Timer tick, asking Buildbucket for the build status
[10:37:22.673] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:22.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:5:0) after 1m0s
[10:38:22.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:5:0)
[10:38:22.735] Timer tick, asking Buildbucket for the build status
[10:38:22.827] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:22.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:6:0) after 1m0s
[10:39:22.876] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:6:0)
[10:39:22.876] Timer tick, asking Buildbucket for the build status
[10:39:22.944] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:22.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:7:0) after 1m0s
[10:40:23.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:7:0)
[10:40:23.079] Timer tick, asking Buildbucket for the build status
[10:40:23.166] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:23.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:8:0) after 1m0s
[10:41:23.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:8:0)
[10:41:23.232] Timer tick, asking Buildbucket for the build status
[10:41:23.338] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:23.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:9:0) after 1m0s
[10:42:23.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:9:0)
[10:42:23.359] Timer tick, asking Buildbucket for the build status
[10:42:23.602] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:23.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:10:0) after 1m0s
[10:43:23.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:10:0)
[10:43:23.622] Timer tick, asking Buildbucket for the build status
[10:43:23.700] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:23.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:11:0) after 1m0s
[10:44:23.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:11:0)
[10:44:23.765] Timer tick, asking Buildbucket for the build status
[10:44:23.884] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:23.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:12:0) after 1m0s
[10:45:23.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:12:0)
[10:45:23.899] Timer tick, asking Buildbucket for the build status
[10:45:23.989] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:23.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:13:0) after 1m0s
[10:46:24.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:13:0)
[10:46:24.017] Timer tick, asking Buildbucket for the build status
[10:46:24.081] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:24.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:14:0) after 1m0s
[10:47:24.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:14:0)
[10:47:24.130] Timer tick, asking Buildbucket for the build status
[10:47:24.213] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:24.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:15:0) after 1m0s
[10:48:24.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:15:0)
[10:48:24.392] Timer tick, asking Buildbucket for the build status
[10:48:24.516] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:24.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:16:0) after 1m0s
[10:49:24.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:16:0)
[10:49:24.525] Timer tick, asking Buildbucket for the build status
[10:49:24.652] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:24.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:17:0) after 1m0s
[10:50:24.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:17:0)
[10:50:24.669] Timer tick, asking Buildbucket for the build status
[10:50:25.031] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:25.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:18:0) after 1m0s
[10:51:25.223] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:18:0)
[10:51:25.223] Timer tick, asking Buildbucket for the build status
[10:51:25.281] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:25.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:19:0) after 1m0s
[10:52:25.324] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:19:0)
[10:52:25.324] Timer tick, asking Buildbucket for the build status
[10:52:25.381] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:25.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:20:0) after 1m0s
[10:53:25.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:20:0)
[10:53:25.570] Timer tick, asking Buildbucket for the build status
[10:53:25.642] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:25.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:21:0) after 1m0s
[10:54:25.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:21:0)
[10:54:25.894] Timer tick, asking Buildbucket for the build status
[10:54:25.953] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:25.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:22:0) after 1m0s
[10:55:25.965] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:22:0)
[10:55:25.965] Timer tick, asking Buildbucket for the build status
[10:55:26.177] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:26.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:23:0) after 1m0s
[10:56:26.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:23:0)
[10:56:26.237] Timer tick, asking Buildbucket for the build status
[10:56:26.307] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:26.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:24:0) after 1m0s
[10:57:26.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:24:0)
[10:57:26.332] Timer tick, asking Buildbucket for the build status
[10:57:26.475] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:26.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:25:0) after 1m0s
[10:58:26.540] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:25:0)
[10:58:26.540] Timer tick, asking Buildbucket for the build status
[10:58:26.640] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:26.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:26:0) after 1m0s
[10:59:26.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:26:0)
[10:59:26.746] Timer tick, asking Buildbucket for the build status
[10:59:26.804] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:26.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:27:0) after 1m0s
[11:00:26.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:27:0)
[11:00:26.872] Timer tick, asking Buildbucket for the build status
[11:00:27.015] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:27.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:28:0) after 1m0s
[11:01:27.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:28:0)
[11:01:27.123] Timer tick, asking Buildbucket for the build status
[11:01:27.191] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:27.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:29:0) after 1m0s
[11:02:27.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:29:0)
[11:02:27.278] Timer tick, asking Buildbucket for the build status
[11:02:27.357] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:27.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:30:0) after 1m0s
[11:03:27.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:30:0)
[11:03:27.403] Timer tick, asking Buildbucket for the build status
[11:03:27.465] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:27.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:31:0) after 1m0s
[11:04:27.489] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:31:0)
[11:04:27.489] Timer tick, asking Buildbucket for the build status
[11:04:27.561] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:27.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:32:0) after 1m0s
[11:05:27.578] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:32:0)
[11:05:27.578] Timer tick, asking Buildbucket for the build status
[11:05:27.784] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:27.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:33:0) after 1m0s
[11:06:27.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:33:0)
[11:06:27.818] Timer tick, asking Buildbucket for the build status
[11:06:27.977] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:27.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:34:0) after 1m0s
[11:07:28.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:34:0)
[11:07:28.045] Timer tick, asking Buildbucket for the build status
[11:07:28.208] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:28.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:35:0) after 1m0s
[11:08:28.300] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:35:0)
[11:08:28.300] Timer tick, asking Buildbucket for the build status
[11:08:28.361] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:28.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:36:0) after 1m0s
[11:09:28.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:36:0)
[11:09:28.387] Timer tick, asking Buildbucket for the build status
[11:09:28.481] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:28.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:37:0) after 1m0s
[11:10:28.463] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:37:0)
[11:10:28.463] Timer tick, asking Buildbucket for the build status
[11:10:28.797] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:28.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:38:0) after 1m0s
[11:11:28.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:38:0)
[11:11:28.849] Timer tick, asking Buildbucket for the build status
[11:11:29.003] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:29.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:39:0) after 1m0s
[11:12:29.117] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:39:0)
[11:12:29.117] Timer tick, asking Buildbucket for the build status
[11:12:29.228] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:29.228] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:40:0) after 1m0s
[11:13:29.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:40:0)
[11:13:29.274] Timer tick, asking Buildbucket for the build status
[11:13:29.385] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:29.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:41:0) after 1m0s
[11:14:29.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:41:0)
[11:14:29.764] Timer tick, asking Buildbucket for the build status
[11:14:29.823] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:29.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:42:0) after 1m0s
[11:15:29.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:42:0)
[11:15:29.978] Timer tick, asking Buildbucket for the build status
[11:15:30.100] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:30.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:43:0) after 1m0s
[11:16:30.225] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:43:0)
[11:16:30.225] Timer tick, asking Buildbucket for the build status
[11:16:30.322] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:30.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:44:0) after 1m0s
[11:17:30.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:44:0)
[11:17:30.346] Timer tick, asking Buildbucket for the build status
[11:17:30.411] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:30.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:45:0) after 1m0s
[11:18:30.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:45:0)
[11:18:30.439] Timer tick, asking Buildbucket for the build status
[11:18:30.712] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:30.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:46:0) after 1m0s
[11:19:30.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:46:0)
[11:19:30.768] Timer tick, asking Buildbucket for the build status
[11:19:30.848] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:30.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:47:0) after 1m0s
[11:20:30.892] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:47:0)
[11:20:30.892] Timer tick, asking Buildbucket for the build status
[11:20:31.283] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:31.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:48:0) after 1m0s
[11:21:31.301] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:48:0)
[11:21:31.301] Timer tick, asking Buildbucket for the build status
[11:21:31.395] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:31.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:49:0) after 1m0s
[11:22:31.440] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:49:0)
[11:22:31.440] Timer tick, asking Buildbucket for the build status
[11:22:31.498] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:31.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:50:0) after 1m0s
[11:23:31.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:50:0)
[11:23:31.528] Timer tick, asking Buildbucket for the build status
[11:23:31.632] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:31.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:51:0) after 1m0s
[11:24:31.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:51:0)
[11:24:31.728] Timer tick, asking Buildbucket for the build status
[11:24:31.827] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:31.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:52:0) after 1m0s
[11:25:31.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:52:0)
[11:25:31.861] Timer tick, asking Buildbucket for the build status
[11:25:32.193] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:32.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:53:0) after 1m0s
[11:26:32.244] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:53:0)
[11:26:32.244] Timer tick, asking Buildbucket for the build status
[11:26:32.301] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:32.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:54:0) after 1m0s
[11:27:32.334] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:54:0)
[11:27:32.334] Timer tick, asking Buildbucket for the build status
[11:27:32.558] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:32.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:55:0) after 1m0s
[11:28:32.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:55:0)
[11:28:32.581] Timer tick, asking Buildbucket for the build status
[11:28:32.649] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:32.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:56:0) after 1m0s
[11:29:32.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:56:0)
[11:29:32.678] Timer tick, asking Buildbucket for the build status
[11:29:33.852] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:33.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:57:0) after 1m0s
[11:30:33.877] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:57:0)
[11:30:33.877] Timer tick, asking Buildbucket for the build status
[11:30:34.101] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:34.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:58:0) after 1m0s
[11:31:34.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:58:0)
[11:31:34.186] Timer tick, asking Buildbucket for the build status
[11:31:34.245] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:34.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:59:0) after 1m0s
[11:32:34.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:59:0)
[11:32:34.256] Timer tick, asking Buildbucket for the build status
[11:32:34.550] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:34.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:60:0) after 1m0s
[11:33:34.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:60:0)
[11:33:34.618] Timer tick, asking Buildbucket for the build status
[11:33:34.757] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:34.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:61:0) after 1m0s
[11:34:34.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:61:0)
[11:34:34.829] Timer tick, asking Buildbucket for the build status
[11:34:34.883] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:34.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:62:0) after 1m0s
[11:35:34.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:62:0)
[11:35:34.955] Timer tick, asking Buildbucket for the build status
[11:35:35.263] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:35.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:63:0) after 1m0s
[11:36:35.288] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:63:0)
[11:36:35.288] Timer tick, asking Buildbucket for the build status
[11:36:35.541] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:35.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:64:0) after 1m0s
[11:37:35.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:64:0)
[11:37:35.586] Timer tick, asking Buildbucket for the build status
[11:37:35.674] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:35.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:65:0) after 1m0s
[11:38:35.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:65:0)
[11:38:35.735] Timer tick, asking Buildbucket for the build status
[11:38:35.791] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:35.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:66:0) after 1m0s
[11:39:35.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:66:0)
[11:39:35.832] Timer tick, asking Buildbucket for the build status
[11:39:35.885] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:35.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:67:0) after 1m0s
[11:40:35.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:67:0)
[11:40:35.926] Timer tick, asking Buildbucket for the build status
[11:40:36.082] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:36.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:68:0) after 1m0s
[11:41:36.112] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:68:0)
[11:41:36.112] Timer tick, asking Buildbucket for the build status
[11:41:36.172] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:36.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:69:0) after 1m0s
[11:42:36.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:69:0)
[11:42:36.227] Timer tick, asking Buildbucket for the build status
[11:42:36.291] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:36.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:70:0) after 1m0s
[11:43:36.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:70:0)
[11:43:36.352] Timer tick, asking Buildbucket for the build status
[11:43:36.428] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:36.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:71:0) after 1m0s
[11:44:36.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:71:0)
[11:44:36.447] Timer tick, asking Buildbucket for the build status
[11:44:36.632] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:36.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:72:0) after 1m0s
[11:45:36.661] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:72:0)
[11:45:36.661] Timer tick, asking Buildbucket for the build status
[11:45:36.790] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:36.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:73:0) after 1m0s
[11:46:36.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:73:0)
[11:46:36.820] Timer tick, asking Buildbucket for the build status
[11:46:36.884] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:36.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:74:0) after 1m0s
[11:47:36.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:74:0)
[11:47:36.937] Timer tick, asking Buildbucket for the build status
[11:47:37.074] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:37.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:75:0) after 1m0s
[11:48:37.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:75:0)
[11:48:37.122] Timer tick, asking Buildbucket for the build status
[11:48:37.188] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:37.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:76:0) after 1m0s
[11:49:37.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:76:0)
[11:49:37.217] Timer tick, asking Buildbucket for the build status
[11:49:37.493] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:37.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:77:0) after 1m0s
[11:50:37.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:77:0)
[11:50:37.493] Timer tick, asking Buildbucket for the build status
[11:50:37.931] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:37.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:78:0) after 1m0s
[11:51:37.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:78:0)
[11:51:37.941] Timer tick, asking Buildbucket for the build status
[11:51:38.495] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:38.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:79:0) after 1m0s
[11:52:38.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:79:0)
[11:52:38.555] Timer tick, asking Buildbucket for the build status
[11:52:38.629] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:38.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:80:0) after 1m0s
[11:53:38.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:80:0)
[11:53:38.663] Timer tick, asking Buildbucket for the build status
[11:53:38.723] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:38.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:81:0) after 1m0s
[11:54:38.748] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:81:0)
[11:54:38.748] Timer tick, asking Buildbucket for the build status
[11:54:38.809] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:38.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:82:0) after 1m0s
[11:55:38.840] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:82:0)
[11:55:38.840] Timer tick, asking Buildbucket for the build status
[11:55:38.942] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:38.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:83:0) after 1m0s
[11:56:38.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:83:0)
[11:56:38.969] Timer tick, asking Buildbucket for the build status
[11:56:39.032] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:39.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:84:0) after 1m0s
[11:57:39.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:84:0)
[11:57:39.036] Timer tick, asking Buildbucket for the build status
[11:57:39.172] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:39.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:85:0) after 1m0s
[11:58:39.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:85:0)
[11:58:39.345] Timer tick, asking Buildbucket for the build status
[11:58:39.511] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:39.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:86:0) after 1m0s
[11:59:39.772] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:86:0)
[11:59:39.772] Timer tick, asking Buildbucket for the build status
[11:59:40.410] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:40.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:87:0) after 1m0s
[12:00:40.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:87:0)
[12:00:40.554] Timer tick, asking Buildbucket for the build status
[12:00:40.728] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:40.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:88:0) after 1m0s
[12:01:40.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:88:0)
[12:01:40.827] Timer tick, asking Buildbucket for the build status
[12:01:40.915] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:40.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:89:0) after 1m0s
[12:02:40.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:89:0)
[12:02:40.996] Timer tick, asking Buildbucket for the build status
[12:02:41.097] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:41.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:90:0) after 1m0s
[12:03:41.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:90:0)
[12:03:41.104] Timer tick, asking Buildbucket for the build status
[12:03:41.183] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:41.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:91:0) after 1m0s
[12:04:41.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:91:0)
[12:04:41.218] Timer tick, asking Buildbucket for the build status
[12:04:41.287] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:41.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:92:0) after 1m0s
[12:05:41.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:92:0)
[12:05:41.347] Timer tick, asking Buildbucket for the build status
[12:05:41.476] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:41.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:93:0) after 1m0s
[12:06:41.562] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:93:0)
[12:06:41.562] Timer tick, asking Buildbucket for the build status
[12:06:43.323] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:43.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:94:0) after 1m0s
[12:07:43.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:94:0)
[12:07:43.460] Timer tick, asking Buildbucket for the build status
[12:07:43.565] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:43.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:95:0) after 1m0s
[12:08:43.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:95:0)
[12:08:43.612] Timer tick, asking Buildbucket for the build status
[12:08:43.685] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:43.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:96:0) after 1m0s
[12:09:43.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:96:0)
[12:09:43.742] Timer tick, asking Buildbucket for the build status
[12:09:44.630] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:44.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:97:0) after 1m0s
[12:10:44.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:97:0)
[12:10:44.764] Timer tick, asking Buildbucket for the build status
[12:10:45.154] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:45.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:98:0) after 1m0s
[12:11:45.198] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:98:0)
[12:11:45.198] Timer tick, asking Buildbucket for the build status
[12:11:45.551] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:45.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:99:0) after 1m0s
[12:12:45.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:99:0)
[12:12:45.658] Timer tick, asking Buildbucket for the build status
[12:12:46.095] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:46.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:100:0) after 1m0s
[12:13:46.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:100:0)
[12:13:46.140] Timer tick, asking Buildbucket for the build status
[12:13:46.530] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:46.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:101:0) after 1m0s
[12:14:46.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:101:0)
[12:14:46.556] Timer tick, asking Buildbucket for the build status
[12:14:46.618] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:46.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:102:0) after 1m0s
[12:15:46.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:102:0)
[12:15:46.634] Timer tick, asking Buildbucket for the build status
[12:15:46.779] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:46.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:103:0) after 1m0s
[12:16:46.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:103:0)
[12:16:46.807] Timer tick, asking Buildbucket for the build status
[12:16:47.144] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:47.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:104:0) after 1m0s
[12:17:47.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:104:0)
[12:17:47.176] Timer tick, asking Buildbucket for the build status
[12:17:47.244] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:47.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:105:0) after 1m0s
[12:18:47.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:105:0)
[12:18:47.286] Timer tick, asking Buildbucket for the build status
[12:18:47.360] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:47.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:106:0) after 1m0s
[12:19:47.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:106:0)
[12:19:47.418] Timer tick, asking Buildbucket for the build status
[12:19:47.482] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:47.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:107:0) after 1m0s
[12:20:47.532] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:107:0)
[12:20:47.532] Timer tick, asking Buildbucket for the build status
[12:20:47.647] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:47.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:108:0) after 1m0s
[12:21:47.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:108:0)
[12:21:47.696] Timer tick, asking Buildbucket for the build status
[12:21:47.845] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:47.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:109:0) after 1m0s
[12:22:47.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:109:0)
[12:22:47.824] Timer tick, asking Buildbucket for the build status
[12:22:48.372] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:48.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:110:0) after 1m0s
[12:23:48.411] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:110:0)
[12:23:48.412] Timer tick, asking Buildbucket for the build status
[12:23:48.473] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:48.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:111:0) after 1m0s
[12:24:48.542] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:111:0)
[12:24:48.542] Timer tick, asking Buildbucket for the build status
[12:24:48.618] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:48.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:112:0) after 1m0s
[12:25:48.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:112:0)
[12:25:48.657] Timer tick, asking Buildbucket for the build status
[12:25:48.782] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:48.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:113:0) after 1m0s
[12:26:48.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:113:0)
[12:26:48.816] Timer tick, asking Buildbucket for the build status
[12:26:48.879] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:48.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:114:0) after 1m0s
[12:27:48.930] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:114:0)
[12:27:48.930] Timer tick, asking Buildbucket for the build status
[12:27:49.129] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:49.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:115:0) after 1m0s
[12:28:49.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:115:0)
[12:28:49.158] Timer tick, asking Buildbucket for the build status
[12:28:49.218] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:49.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:116:0) after 1m0s
[12:29:49.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:116:0)
[12:29:49.237] Timer tick, asking Buildbucket for the build status
[12:29:49.573] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:49.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:117:0) after 1m0s
[12:30:49.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:117:0)
[12:30:49.627] Timer tick, asking Buildbucket for the build status
[12:30:49.793] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:49.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:118:0) after 1m0s
[12:31:49.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:118:0)
[12:31:49.896] Timer tick, asking Buildbucket for the build status
[12:31:49.958] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:49.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:119:0) after 1m0s
[12:32:49.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:119:0)
[12:32:49.983] Timer tick, asking Buildbucket for the build status
[12:32:50.064] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:50.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:120:0) after 1m0s
[12:33:50.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:120:0)
[12:33:50.123] Timer tick, asking Buildbucket for the build status
[12:33:50.197] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:50.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:121:0) after 1m0s
[12:34:50.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:121:0)
[12:34:50.231] Timer tick, asking Buildbucket for the build status
[12:34:50.296] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:50.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:122:0) after 1m0s
[12:35:50.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:122:0)
[12:35:50.356] Timer tick, asking Buildbucket for the build status
[12:35:50.467] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:50.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:123:0) after 1m0s
[12:36:50.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:123:0)
[12:36:50.491] Timer tick, asking Buildbucket for the build status
[12:36:50.565] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:50.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:124:0) after 1m0s
[12:37:51.466] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:124:0)
[12:37:51.466] Timer tick, asking Buildbucket for the build status
[12:37:51.708] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:51.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:125:0) after 1m0s
[12:38:51.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:125:0)
[12:38:51.912] Timer tick, asking Buildbucket for the build status
[12:38:51.976] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:51.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:126:0) after 1m0s
[12:39:52.090] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:126:0)
[12:39:52.090] Timer tick, asking Buildbucket for the build status
[12:39:52.156] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:52.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:127:0) after 1m0s
[12:40:52.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:127:0)
[12:40:52.241] Timer tick, asking Buildbucket for the build status
[12:40:52.359] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:52.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:128:0) after 1m0s
[12:41:52.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:128:0)
[12:41:52.425] Timer tick, asking Buildbucket for the build status
[12:41:52.806] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:52.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:129:0) after 1m0s
[12:42:52.868] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:129:0)
[12:42:52.868] Timer tick, asking Buildbucket for the build status
[12:42:53.137] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:53.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:130:0) after 1m0s
[12:43:53.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:130:0)
[12:43:53.235] Timer tick, asking Buildbucket for the build status
[12:43:53.305] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:53.305] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:131:0) after 1m0s
[12:44:53.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:131:0)
[12:44:53.340] Timer tick, asking Buildbucket for the build status
[12:44:53.407] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:53.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:132:0) after 1m0s
[12:45:53.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:132:0)
[12:45:53.439] Timer tick, asking Buildbucket for the build status
[12:45:54.002] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:54.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:133:0) after 1m0s
[12:46:54.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:133:0)
[12:46:54.044] Timer tick, asking Buildbucket for the build status
[12:46:54.196] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:54.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:134:0) after 1m0s
[12:47:54.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:134:0)
[12:47:54.209] Timer tick, asking Buildbucket for the build status
[12:47:54.283] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:54.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:135:0) after 1m0s
[12:48:54.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:135:0)
[12:48:54.308] Timer tick, asking Buildbucket for the build status
[12:48:54.388] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:54.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:136:0) after 1m0s
[12:49:54.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:136:0)
[12:49:54.417] Timer tick, asking Buildbucket for the build status
[12:49:54.477] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:54.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:137:0) after 1m0s
[12:50:54.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:137:0)
[12:50:54.502] Timer tick, asking Buildbucket for the build status
[12:50:54.620] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:54.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:138:0) after 1m0s
[12:51:54.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:138:0)
[12:51:54.646] Timer tick, asking Buildbucket for the build status
[12:51:54.711] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:54.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:139:0) after 1m0s
[12:52:54.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:139:0)
[12:52:54.790] Timer tick, asking Buildbucket for the build status
[12:52:54.847] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:54.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:140:0) after 1m0s
[12:53:54.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:140:0)
[12:53:54.873] Timer tick, asking Buildbucket for the build status
[12:53:54.938] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:54.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:141:0) after 1m0s
[12:54:54.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:141:0)
[12:54:54.997] Timer tick, asking Buildbucket for the build status
[12:54:55.066] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:55.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:142:0) after 1m0s
[12:55:55.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:142:0)
[12:55:55.108] Timer tick, asking Buildbucket for the build status
[12:55:55.276] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:55.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:143:0) after 1m0s
[12:56:55.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:143:0)
[12:56:55.369] Timer tick, asking Buildbucket for the build status
[12:56:55.696] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:144:0) after 1m0s
[12:57:55.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:144:0)
[12:57:55.701] Timer tick, asking Buildbucket for the build status
[12:57:55.768] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:55.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:145:0) after 1m0s
[12:58:55.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:145:0)
[12:58:55.813] Timer tick, asking Buildbucket for the build status
[12:58:55.881] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:55.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:146:0) after 1m0s
[12:59:55.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:146:0)
[12:59:55.910] Timer tick, asking Buildbucket for the build status
[12:59:55.966] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:55.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:147:0) after 1m0s
[13:00:56.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:147:0)
[13:00:56.006] Timer tick, asking Buildbucket for the build status
[13:00:56.151] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:56.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:148:0) after 1m0s
[13:01:56.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:148:0)
[13:01:56.207] Timer tick, asking Buildbucket for the build status
[13:01:56.288] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:56.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:149:0) after 1m0s
[13:02:56.365] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:149:0)
[13:02:56.365] Timer tick, asking Buildbucket for the build status
[13:02:56.542] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:56.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:150:0) after 1m0s
[13:03:56.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:150:0)
[13:03:56.572] Timer tick, asking Buildbucket for the build status
[13:03:56.669] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:56.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:151:0) after 1m0s
[13:04:56.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:151:0)
[13:04:56.823] Timer tick, asking Buildbucket for the build status
[13:04:57.025] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:57.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:152:0) after 1m0s
[13:05:57.148] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:152:0)
[13:05:57.148] Timer tick, asking Buildbucket for the build status
[13:05:57.390] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:57.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:153:0) after 1m0s
[13:06:57.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:153:0)
[13:06:57.429] Timer tick, asking Buildbucket for the build status
[13:06:57.534] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:57.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:154:0) after 1m0s
[13:07:57.587] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:154:0)
[13:07:57.587] Timer tick, asking Buildbucket for the build status
[13:07:57.653] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:57.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:155:0) after 1m0s
[13:08:57.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:155:0)
[13:08:57.667] Timer tick, asking Buildbucket for the build status
[13:08:57.741] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:57.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:156:0) after 1m0s
[13:09:57.838] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:156:0)
[13:09:57.838] Timer tick, asking Buildbucket for the build status
[13:09:58.006] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:58.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:157:0) after 1m0s
[13:10:58.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:157:0)
[13:10:58.086] Timer tick, asking Buildbucket for the build status
[13:10:58.189] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:58.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:158:0) after 1m0s
[13:11:58.248] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:158:0)
[13:11:58.248] Timer tick, asking Buildbucket for the build status
[13:11:58.314] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:58.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:159:0) after 1m0s
[13:12:58.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:159:0)
[13:12:58.364] Timer tick, asking Buildbucket for the build status
[13:12:58.425] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:58.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:160:0) after 1m0s
[13:13:58.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:160:0)
[13:13:58.405] Timer tick, asking Buildbucket for the build status
[13:13:58.621] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:58.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:161:0) after 1m0s
[13:14:58.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:161:0)
[13:14:58.700] Timer tick, asking Buildbucket for the build status
[13:14:58.791] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:58.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:162:0) after 1m0s
[13:15:58.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:162:0)
[13:15:58.805] Timer tick, asking Buildbucket for the build status
[13:15:59.529] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:59.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:163:0) after 1m0s
[13:16:59.606] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:163:0)
[13:16:59.606] Timer tick, asking Buildbucket for the build status
[13:16:59.782] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:59.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:164:0) after 1m0s
[13:17:59.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:164:0)
[13:17:59.820] Timer tick, asking Buildbucket for the build status
[13:17:59.893] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:59.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:165:0) after 1m0s
[13:18:59.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:165:0)
[13:18:59.953] Timer tick, asking Buildbucket for the build status
[13:19:00.224] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:00.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:166:0) after 1m0s
[13:20:00.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:166:0)
[13:20:00.314] Timer tick, asking Buildbucket for the build status
[13:20:00.626] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:00.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:167:0) after 1m0s
[13:21:00.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:167:0)
[13:21:00.657] Timer tick, asking Buildbucket for the build status
[13:21:01.009] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:01.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:168:0) after 1m0s
[13:22:01.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:168:0)
[13:22:01.287] Timer tick, asking Buildbucket for the build status
[13:22:01.584] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:01.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:169:0) after 1m0s
[13:23:01.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:169:0)
[13:23:01.614] Timer tick, asking Buildbucket for the build status
[13:23:01.695] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:01.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:170:0) after 1m0s
[13:24:01.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:170:0)
[13:24:01.781] Timer tick, asking Buildbucket for the build status
[13:24:02.031] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:02.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:171:0) after 1m0s
[13:25:02.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:171:0)
[13:25:02.118] Timer tick, asking Buildbucket for the build status
[13:25:02.350] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:02.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:172:0) after 1m0s
[13:26:02.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:172:0)
[13:26:02.372] Timer tick, asking Buildbucket for the build status
[13:26:02.476] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:02.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:173:0) after 1m0s
[13:27:02.523] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:173:0)
[13:27:02.523] Timer tick, asking Buildbucket for the build status
[13:27:02.619] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:02.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:174:0) after 1m0s
[13:28:02.663] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:174:0)
[13:28:02.663] Timer tick, asking Buildbucket for the build status
[13:28:02.733] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:02.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:175:0) after 1m0s
[13:29:02.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:175:0)
[13:29:02.787] Timer tick, asking Buildbucket for the build status
[13:29:02.979] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:02.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:176:0) after 1m0s
[13:30:02.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:176:0)
[13:30:02.999] Timer tick, asking Buildbucket for the build status
[13:30:03.510] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:03.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:177:0) after 1m0s
[13:31:03.622] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:177:0)
[13:31:03.623] Timer tick, asking Buildbucket for the build status
[13:31:03.776] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:03.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:178:0) after 1m0s
[13:32:03.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:178:0)
[13:32:03.898] Timer tick, asking Buildbucket for the build status
[13:32:04.192] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:04.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:179:0) after 1m0s
[13:33:04.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:179:0)
[13:33:04.261] Timer tick, asking Buildbucket for the build status
[13:33:04.415] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:04.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:180:0) after 1m0s
[13:34:04.531] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:180:0)
[13:34:04.531] Timer tick, asking Buildbucket for the build status
[13:34:04.592] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:04.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:181:0) after 1m0s
[13:35:04.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:181:0)
[13:35:04.658] Timer tick, asking Buildbucket for the build status
[13:35:04.792] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:04.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:182:0) after 1m0s
[13:36:04.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:182:0)
[13:36:04.863] Timer tick, asking Buildbucket for the build status
[13:36:05.058] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:05.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:183:0) after 1m0s
[13:37:05.123] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:183:0)
[13:37:05.123] Timer tick, asking Buildbucket for the build status
[13:37:05.299] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:05.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:184:0) after 1m0s
[13:38:05.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:184:0)
[13:38:05.326] Timer tick, asking Buildbucket for the build status
[13:38:05.528] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:05.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:185:0) after 1m0s
[13:39:05.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:185:0)
[13:39:05.671] Timer tick, asking Buildbucket for the build status
[13:39:05.805] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:05.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:186:0) after 1m0s
[13:40:06.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:186:0)
[13:40:06.077] Timer tick, asking Buildbucket for the build status
[13:40:06.144] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:06.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:187:0) after 1m0s
[13:41:06.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:187:0)
[13:41:06.386] Timer tick, asking Buildbucket for the build status
[13:41:06.498] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:06.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:188:0) after 1m0s
[13:42:06.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:188:0)
[13:42:06.712] Timer tick, asking Buildbucket for the build status
[13:42:06.815] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:06.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:189:0) after 1m0s
[13:43:07.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:189:0)
[13:43:07.095] Timer tick, asking Buildbucket for the build status
[13:43:07.262] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:07.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:190:0) after 1m0s
[13:44:07.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:190:0)
[13:44:07.402] Timer tick, asking Buildbucket for the build status
[13:44:08.216] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:08.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:191:0) after 1m0s
[13:45:08.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:191:0)
[13:45:08.250] Timer tick, asking Buildbucket for the build status
[13:45:09.046] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:09.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:192:0) after 1m0s
[13:46:09.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:192:0)
[13:46:09.086] Timer tick, asking Buildbucket for the build status
[13:46:09.495] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:09.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:193:0) after 1m0s
[13:47:09.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:193:0)
[13:47:09.546] Timer tick, asking Buildbucket for the build status
[13:47:09.631] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:09.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:194:0) after 1m0s
[13:48:09.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:194:0)
[13:48:09.729] Timer tick, asking Buildbucket for the build status
[13:48:09.989] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:09.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:195:0) after 1m0s
[13:49:10.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:195:0)
[13:49:10.111] Timer tick, asking Buildbucket for the build status
[13:49:10.219] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:10.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:196:0) after 1m0s
[13:50:10.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:196:0)
[13:50:10.263] Timer tick, asking Buildbucket for the build status
[13:50:10.501] Build 8857682706855041056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:10.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9023112768849592688:197:0) after 1m0s
[13:50:16.131] Received PubSub notification, asking Buildbucket for the build status
[13:50:16.191] Build 8857682706855041056: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:50:16.191] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611064214547803",
"created_by": "project:chromium",
"created_ts": "1611052521804908",
"id": "8857682706855041056",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d9bf8f79861573a7512e74031f08ac28834c6ded\"}}",
"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\": \"d9bf8f79861573a7512e74031f08ac28834c6ded\", \"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-ndrv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1414, \"got_angle_revision\": \"81430e11c81e26edc9fd0cf9f6f9f65c8e1011f7\", \"got_dawn_revision\": \"4b316994ad01ab6b87032c5fd9b53c6d18956d8d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d9bf8f79861573a7512e74031f08ac28834c6ded\", \"got_revision_cp\": \"refs/heads/master@{#844718}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"8da0f82baf359e2b91464f111306a094f86fe71b\", \"got_v8_revision\": \"ee927aaa58e688ad6aa0256b3929c1eeea0bebd5\", \"got_v8_revision_cp\": \"refs/heads/9.0.8@{#1}\", \"got_webrtc_revision\": \"29bd8638adb0a7294f7b8ae589fdd24077539dbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33030}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d9bf8f79861573a7512e74031f08ac28834c6ded\"}, \"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-ndrv\"], \"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\": [\"5666-679c9bd\"], \"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": "1611052578991701",
"status": "COMPLETED",
"status_changed_ts": "1611064215279148",
"tags": [
"build_address:luci.chromium.ci/win-official/1414",
"builder:win-official",
"buildset:commit/git/d9bf8f79861573a7512e74031f08ac28834c6ded",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d9bf8f79861573a7512e74031f08ac28834c6ded",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023112768849592688",
"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/8857682706855041056/+/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:513308253821f410",
"user_agent:luci-scheduler"
],
"updated_ts": "1611064215435954",
"url": "https://ci.chromium.org/b/8857682706855041056",
"utcnow_ts": "1611064216182828"
}
}
[13:50:16.191] Invocation finished in 3h14m55.672704238s with status SUCCEEDED