[06:43:41.629] New invocation is queued and will start shortly
[06:43:42.769] Starting the invocation (attempt 1)
[06:43:42.802] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:43:42.802] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:43:42.803] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021406000543962368",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"685c8b859043994eff82974a3e0e5a3cc0d31a9f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021406000543962368",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/685c8b859043994eff82974a3e0e5a3cc0d31a9f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/685c8b859043994eff82974a3e0e5a3cc0d31a9f",
"gitiles_ref:refs/heads/master"
]
}
[06:43:43.464] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612680222898490",
"id": "8855975938552695792",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"685c8b859043994eff82974a3e0e5a3cc0d31a9f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612680223246858",
"tags": [
"build_address:luci.chromium.ci/win-official/1555",
"builder:win-official",
"buildset:commit/git/685c8b859043994eff82974a3e0e5a3cc0d31a9f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/685c8b859043994eff82974a3e0e5a3cc0d31a9f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021406000543962368",
"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/8855975938552695792/+/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": "1612680223247459",
"url": "https://ci.chromium.org/b/8855975938552695792",
"utcnow_ts": "1612680223448360"
}
}
[06:43:43.464] Task URL: https://ci.chromium.org/b/8855975938552695792
[06:43:43.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:2:0) after 1m0s
[06:44:43.677] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:2:0)
[06:44:43.677] Timer tick, asking Buildbucket for the build status
[06:44:43.777] Build 8855975938552695792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:44:43.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:3:0) after 1m0s
[06:45:43.864] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:3:0)
[06:45:43.864] Timer tick, asking Buildbucket for the build status
[06:45:43.933] Build 8855975938552695792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:45:43.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:4:0) after 1m0s
[06:46:43.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:4:0)
[06:46:43.940] Timer tick, asking Buildbucket for the build status
[06:46:44.014] Build 8855975938552695792: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[06:46:44.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:5:0) after 1m0s
[06:47:27.870] Received PubSub notification, asking Buildbucket for the build status
[06:47:27.992] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:44.027] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:5:0)
[06:47:44.027] Timer tick, asking Buildbucket for the build status
[06:47:44.091] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:44.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:7:0) after 1m0s
[06:48:44.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:7:0)
[06:48:44.154] Timer tick, asking Buildbucket for the build status
[06:48:44.235] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:44.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:8:0) after 1m0s
[06:49:44.342] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:8:0)
[06:49:44.342] Timer tick, asking Buildbucket for the build status
[06:49:44.591] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:44.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:9:0) after 1m0s
[06:50:44.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:9:0)
[06:50:44.674] Timer tick, asking Buildbucket for the build status
[06:50:44.753] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:44.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:10:0) after 1m0s
[06:51:44.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:10:0)
[06:51:44.793] Timer tick, asking Buildbucket for the build status
[06:51:45.016] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:45.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:11:0) after 1m0s
[06:52:45.190] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:11:0)
[06:52:45.190] Timer tick, asking Buildbucket for the build status
[06:52:45.273] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:45.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:12:0) after 1m0s
[06:53:45.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:12:0)
[06:53:45.281] Timer tick, asking Buildbucket for the build status
[06:53:45.858] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:45.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:13:0) after 1m0s
[06:54:45.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:13:0)
[06:54:45.963] Timer tick, asking Buildbucket for the build status
[06:54:46.038] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:46.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:14:0) after 1m0s
[06:55:46.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:14:0)
[06:55:46.130] Timer tick, asking Buildbucket for the build status
[06:55:46.207] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:46.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:15:0) after 1m0s
[06:56:46.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:15:0)
[06:56:46.423] Timer tick, asking Buildbucket for the build status
[06:56:46.800] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:46.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:16:0) after 1m0s
[06:57:46.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:16:0)
[06:57:46.854] Timer tick, asking Buildbucket for the build status
[06:57:47.032] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:47.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:17:0) after 1m0s
[06:58:47.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:17:0)
[06:58:47.068] Timer tick, asking Buildbucket for the build status
[06:58:47.170] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:47.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:18:0) after 1m0s
[06:59:47.343] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:18:0)
[06:59:47.343] Timer tick, asking Buildbucket for the build status
[06:59:47.810] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:47.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:19:0) after 1m0s
[07:00:48.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:19:0)
[07:00:48.020] Timer tick, asking Buildbucket for the build status
[07:00:48.112] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:48.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:20:0) after 1m0s
[07:01:48.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:20:0)
[07:01:48.291] Timer tick, asking Buildbucket for the build status
[07:01:48.452] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:48.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:21:0) after 1m0s
[07:02:48.642] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:21:0)
[07:02:48.642] Timer tick, asking Buildbucket for the build status
[07:02:48.732] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:48.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:22:0) after 1m0s
[07:03:48.841] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:22:0)
[07:03:48.841] Timer tick, asking Buildbucket for the build status
[07:03:49.139] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:49.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:23:0) after 1m0s
[07:04:49.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:23:0)
[07:04:49.174] Timer tick, asking Buildbucket for the build status
[07:04:49.315] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:49.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:24:0) after 1m0s
[07:05:49.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:24:0)
[07:05:49.483] Timer tick, asking Buildbucket for the build status
[07:05:49.790] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:49.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:25:0) after 1m0s
[07:06:49.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:25:0)
[07:06:49.881] Timer tick, asking Buildbucket for the build status
[07:06:49.930] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:49.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:26:0) after 1m0s
[07:07:49.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:26:0)
[07:07:49.998] Timer tick, asking Buildbucket for the build status
[07:07:50.065] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:50.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:27:0) after 1m0s
[07:08:50.184] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:27:0)
[07:08:50.185] Timer tick, asking Buildbucket for the build status
[07:08:50.359] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:50.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:28:0) after 1m0s
[07:09:50.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:28:0)
[07:09:50.378] Timer tick, asking Buildbucket for the build status
[07:09:50.607] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:50.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:29:0) after 1m0s
[07:10:50.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:29:0)
[07:10:50.638] Timer tick, asking Buildbucket for the build status
[07:10:50.711] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:50.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:30:0) after 1m0s
[07:11:51.402] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:30:0)
[07:11:51.402] Timer tick, asking Buildbucket for the build status
[07:11:51.540] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:51.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:31:0) after 1m0s
[07:12:51.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:31:0)
[07:12:51.671] Timer tick, asking Buildbucket for the build status
[07:12:51.998] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:51.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:32:0) after 1m0s
[07:13:52.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:32:0)
[07:13:52.020] Timer tick, asking Buildbucket for the build status
[07:13:52.293] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:52.293] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:33:0) after 1m0s
[07:14:52.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:33:0)
[07:14:52.311] Timer tick, asking Buildbucket for the build status
[07:14:52.409] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:52.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:34:0) after 1m0s
[07:15:52.503] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:34:0)
[07:15:52.503] Timer tick, asking Buildbucket for the build status
[07:15:52.661] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:52.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:35:0) after 1m0s
[07:16:52.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:35:0)
[07:16:52.682] Timer tick, asking Buildbucket for the build status
[07:16:52.775] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:52.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:36:0) after 1m0s
[07:17:52.972] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:36:0)
[07:17:52.972] Timer tick, asking Buildbucket for the build status
[07:17:53.696] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:53.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:37:0) after 1m0s
[07:18:53.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:37:0)
[07:18:53.947] Timer tick, asking Buildbucket for the build status
[07:18:54.086] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:54.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:38:0) after 1m0s
[07:19:54.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:38:0)
[07:19:54.316] Timer tick, asking Buildbucket for the build status
[07:19:54.622] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:54.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:39:0) after 1m0s
[07:20:54.646] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:39:0)
[07:20:54.646] Timer tick, asking Buildbucket for the build status
[07:20:54.714] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:54.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:40:0) after 1m0s
[07:21:54.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:40:0)
[07:21:54.766] Timer tick, asking Buildbucket for the build status
[07:21:55.145] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:55.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:41:0) after 1m0s
[07:22:55.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:41:0)
[07:22:55.186] Timer tick, asking Buildbucket for the build status
[07:22:55.259] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:55.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:42:0) after 1m0s
[07:23:55.330] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:42:0)
[07:23:55.330] Timer tick, asking Buildbucket for the build status
[07:23:55.598] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:55.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:43:0) after 1m0s
[07:24:55.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:43:0)
[07:24:55.575] Timer tick, asking Buildbucket for the build status
[07:24:55.632] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:55.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:44:0) after 1m0s
[07:25:55.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:44:0)
[07:25:55.768] Timer tick, asking Buildbucket for the build status
[07:25:55.994] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:55.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:45:0) after 1m0s
[07:26:56.235] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:45:0)
[07:26:56.235] Timer tick, asking Buildbucket for the build status
[07:26:56.291] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:56.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:46:0) after 1m0s
[07:27:56.386] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:46:0)
[07:27:56.386] Timer tick, asking Buildbucket for the build status
[07:27:56.525] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:56.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:47:0) after 1m0s
[07:28:56.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:47:0)
[07:28:56.513] Timer tick, asking Buildbucket for the build status
[07:28:56.740] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:56.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:48:0) after 1m0s
[07:29:56.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:48:0)
[07:29:56.770] Timer tick, asking Buildbucket for the build status
[07:29:57.132] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:57.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:49:0) after 1m0s
[07:30:57.210] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:49:0)
[07:30:57.210] Timer tick, asking Buildbucket for the build status
[07:30:57.404] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:57.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:50:0) after 1m0s
[07:31:57.526] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:50:0)
[07:31:57.526] Timer tick, asking Buildbucket for the build status
[07:31:57.649] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:57.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:51:0) after 1m0s
[07:32:57.753] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:51:0)
[07:32:57.753] Timer tick, asking Buildbucket for the build status
[07:32:57.811] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:57.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:52:0) after 1m0s
[07:33:57.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:52:0)
[07:33:57.865] Timer tick, asking Buildbucket for the build status
[07:33:58.161] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:58.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:53:0) after 1m0s
[07:34:58.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:53:0)
[07:34:58.142] Timer tick, asking Buildbucket for the build status
[07:34:58.448] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:58.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:54:0) after 1m0s
[07:35:58.541] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:54:0)
[07:35:58.541] Timer tick, asking Buildbucket for the build status
[07:35:58.613] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:58.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:55:0) after 1m0s
[07:36:58.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:55:0)
[07:36:58.612] Timer tick, asking Buildbucket for the build status
[07:36:58.668] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:58.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:56:0) after 1m0s
[07:37:58.681] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:56:0)
[07:37:58.681] Timer tick, asking Buildbucket for the build status
[07:37:58.830] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:58.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:57:0) after 1m0s
[07:38:58.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:57:0)
[07:38:58.842] Timer tick, asking Buildbucket for the build status
[07:38:58.950] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:58.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:58:0) after 1m0s
[07:39:58.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:58:0)
[07:39:58.973] Timer tick, asking Buildbucket for the build status
[07:39:59.333] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:59.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:59:0) after 1m0s
[07:40:59.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:59:0)
[07:40:59.403] Timer tick, asking Buildbucket for the build status
[07:40:59.465] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:59.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:60:0) after 1m0s
[07:41:59.556] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:60:0)
[07:41:59.556] Timer tick, asking Buildbucket for the build status
[07:41:59.622] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:59.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:61:0) after 1m0s
[07:42:59.721] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:61:0)
[07:42:59.721] Timer tick, asking Buildbucket for the build status
[07:42:59.934] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:59.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:62:0) after 1m0s
[07:43:59.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:62:0)
[07:43:59.945] Timer tick, asking Buildbucket for the build status
[07:44:00.150] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:00.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:63:0) after 1m0s
[07:45:00.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:63:0)
[07:45:00.169] Timer tick, asking Buildbucket for the build status
[07:45:00.592] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:00.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:64:0) after 1m0s
[07:46:00.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:64:0)
[07:46:00.689] Timer tick, asking Buildbucket for the build status
[07:46:00.788] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:00.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:65:0) after 1m0s
[07:47:00.858] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:65:0)
[07:47:00.858] Timer tick, asking Buildbucket for the build status
[07:47:01.122] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:01.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:66:0) after 1m0s
[07:48:01.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:66:0)
[07:48:01.146] Timer tick, asking Buildbucket for the build status
[07:48:01.339] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:01.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:67:0) after 1m0s
[07:49:01.368] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:67:0)
[07:49:01.368] Timer tick, asking Buildbucket for the build status
[07:49:01.494] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:01.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:68:0) after 1m0s
[07:50:01.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:68:0)
[07:50:01.602] Timer tick, asking Buildbucket for the build status
[07:50:01.716] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:01.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:69:0) after 1m0s
[07:51:01.736] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:69:0)
[07:51:01.736] Timer tick, asking Buildbucket for the build status
[07:51:01.799] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:01.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:70:0) after 1m0s
[07:52:01.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:70:0)
[07:52:01.957] Timer tick, asking Buildbucket for the build status
[07:52:02.229] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:02.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:71:0) after 1m0s
[07:53:02.296] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:71:0)
[07:53:02.296] Timer tick, asking Buildbucket for the build status
[07:53:02.622] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:02.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:72:0) after 1m0s
[07:54:02.653] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:72:0)
[07:54:02.653] Timer tick, asking Buildbucket for the build status
[07:54:02.778] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:02.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:73:0) after 1m0s
[07:55:02.829] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:73:0)
[07:55:02.829] Timer tick, asking Buildbucket for the build status
[07:55:02.897] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:02.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:74:0) after 1m0s
[07:56:02.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:74:0)
[07:56:02.914] Timer tick, asking Buildbucket for the build status
[07:56:03.048] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:03.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:75:0) after 1m0s
[07:57:03.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:75:0)
[07:57:03.255] Timer tick, asking Buildbucket for the build status
[07:57:03.317] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:03.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:76:0) after 1m0s
[07:58:03.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:76:0)
[07:58:03.431] Timer tick, asking Buildbucket for the build status
[07:58:03.719] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:03.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:77:0) after 1m0s
[07:59:03.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:77:0)
[07:59:03.739] Timer tick, asking Buildbucket for the build status
[07:59:03.837] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:03.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:78:0) after 1m0s
[08:00:03.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:78:0)
[08:00:03.865] Timer tick, asking Buildbucket for the build status
[08:00:03.943] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:03.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:79:0) after 1m0s
[08:01:04.090] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:79:0)
[08:01:04.090] Timer tick, asking Buildbucket for the build status
[08:01:04.150] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:04.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:80:0) after 1m0s
[08:02:04.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:80:0)
[08:02:04.233] Timer tick, asking Buildbucket for the build status
[08:02:04.382] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:04.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:81:0) after 1m0s
[08:03:04.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:81:0)
[08:03:04.378] Timer tick, asking Buildbucket for the build status
[08:03:04.504] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:04.504] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:82:0) after 1m0s
[08:04:04.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:82:0)
[08:04:04.522] Timer tick, asking Buildbucket for the build status
[08:04:04.967] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:04.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:83:0) after 1m0s
[08:05:04.952] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:83:0)
[08:05:04.952] Timer tick, asking Buildbucket for the build status
[08:05:05.013] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:05.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:84:0) after 1m0s
[08:06:05.044] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:84:0)
[08:06:05.044] Timer tick, asking Buildbucket for the build status
[08:06:05.331] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:05.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:85:0) after 1m0s
[08:07:05.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:85:0)
[08:07:05.312] Timer tick, asking Buildbucket for the build status
[08:07:05.382] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:05.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:86:0) after 1m0s
[08:08:05.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:86:0)
[08:08:05.378] Timer tick, asking Buildbucket for the build status
[08:08:05.441] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:05.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:87:0) after 1m0s
[08:09:05.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:87:0)
[08:09:05.521] Timer tick, asking Buildbucket for the build status
[08:09:05.797] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:05.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:88:0) after 1m0s
[08:10:05.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:88:0)
[08:10:05.866] Timer tick, asking Buildbucket for the build status
[08:10:05.933] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:05.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:89:0) after 1m0s
[08:11:06.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:89:0)
[08:11:06.029] Timer tick, asking Buildbucket for the build status
[08:11:06.143] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:06.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:90:0) after 1m0s
[08:12:06.151] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:90:0)
[08:12:06.151] Timer tick, asking Buildbucket for the build status
[08:12:06.213] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:06.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:91:0) after 1m0s
[08:13:06.234] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:91:0)
[08:13:06.234] Timer tick, asking Buildbucket for the build status
[08:13:06.296] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:06.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:92:0) after 1m0s
[08:14:06.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:92:0)
[08:14:06.318] Timer tick, asking Buildbucket for the build status
[08:14:06.459] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:06.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:93:0) after 1m0s
[08:15:06.506] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:93:0)
[08:15:06.506] Timer tick, asking Buildbucket for the build status
[08:15:06.567] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:06.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:94:0) after 1m0s
[08:16:06.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:94:0)
[08:16:06.567] Timer tick, asking Buildbucket for the build status
[08:16:06.636] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:06.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:95:0) after 1m0s
[08:17:06.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:95:0)
[08:17:06.696] Timer tick, asking Buildbucket for the build status
[08:17:07.000] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:07.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:96:0) after 1m0s
[08:18:07.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:96:0)
[08:18:07.021] Timer tick, asking Buildbucket for the build status
[08:18:07.145] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:07.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:97:0) after 1m0s
[08:19:07.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:97:0)
[08:19:07.169] Timer tick, asking Buildbucket for the build status
[08:19:07.332] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:07.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:98:0) after 1m0s
[08:20:07.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:98:0)
[08:20:07.358] Timer tick, asking Buildbucket for the build status
[08:20:07.426] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:07.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:99:0) after 1m0s
[08:21:07.458] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:99:0)
[08:21:07.458] Timer tick, asking Buildbucket for the build status
[08:21:07.617] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:07.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:100:0) after 1m0s
[08:22:07.682] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:100:0)
[08:22:07.682] Timer tick, asking Buildbucket for the build status
[08:22:08.232] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:08.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:101:0) after 1m0s
[08:23:08.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:101:0)
[08:23:08.298] Timer tick, asking Buildbucket for the build status
[08:23:08.627] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:08.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:102:0) after 1m0s
[08:24:08.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:102:0)
[08:24:08.636] Timer tick, asking Buildbucket for the build status
[08:24:08.815] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:08.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:103:0) after 1m0s
[08:25:08.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:103:0)
[08:25:08.797] Timer tick, asking Buildbucket for the build status
[08:25:09.444] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:09.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:104:0) after 1m0s
[08:26:09.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:104:0)
[08:26:09.501] Timer tick, asking Buildbucket for the build status
[08:26:09.579] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:09.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:105:0) after 1m0s
[08:27:09.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:105:0)
[08:27:09.630] Timer tick, asking Buildbucket for the build status
[08:27:09.705] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:09.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:106:0) after 1m0s
[08:28:09.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:106:0)
[08:28:09.728] Timer tick, asking Buildbucket for the build status
[08:28:09.925] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:09.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:107:0) after 1m0s
[08:29:09.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:107:0)
[08:29:09.982] Timer tick, asking Buildbucket for the build status
[08:29:10.096] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:10.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:108:0) after 1m0s
[08:30:10.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:108:0)
[08:30:10.118] Timer tick, asking Buildbucket for the build status
[08:30:10.224] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:10.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:109:0) after 1m0s
[08:31:10.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:109:0)
[08:31:10.277] Timer tick, asking Buildbucket for the build status
[08:31:10.366] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:10.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:110:0) after 1m0s
[08:32:10.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:110:0)
[08:32:10.437] Timer tick, asking Buildbucket for the build status
[08:32:10.525] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:10.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:111:0) after 1m0s
[08:33:10.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:111:0)
[08:33:10.565] Timer tick, asking Buildbucket for the build status
[08:33:10.634] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:10.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:112:0) after 1m0s
[08:34:10.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:112:0)
[08:34:10.796] Timer tick, asking Buildbucket for the build status
[08:34:10.946] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:10.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:113:0) after 1m0s
[08:35:10.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:113:0)
[08:35:10.991] Timer tick, asking Buildbucket for the build status
[08:35:11.081] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:11.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:114:0) after 1m0s
[08:36:11.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:114:0)
[08:36:11.183] Timer tick, asking Buildbucket for the build status
[08:36:11.254] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:11.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:115:0) after 1m0s
[08:37:11.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:115:0)
[08:37:11.648] Timer tick, asking Buildbucket for the build status
[08:37:11.730] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:11.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:116:0) after 1m0s
[08:38:11.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:116:0)
[08:38:11.773] Timer tick, asking Buildbucket for the build status
[08:38:11.846] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:11.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:117:0) after 1m0s
[08:39:11.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:117:0)
[08:39:11.867] Timer tick, asking Buildbucket for the build status
[08:39:11.989] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:11.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:118:0) after 1m0s
[08:40:12.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:118:0)
[08:40:12.030] Timer tick, asking Buildbucket for the build status
[08:40:12.229] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:12.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:119:0) after 1m0s
[08:41:12.410] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:119:0)
[08:41:12.410] Timer tick, asking Buildbucket for the build status
[08:41:12.475] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:12.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:120:0) after 1m0s
[08:42:12.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:120:0)
[08:42:12.641] Timer tick, asking Buildbucket for the build status
[08:42:12.698] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:12.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:121:0) after 1m0s
[08:43:12.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:121:0)
[08:43:12.676] Timer tick, asking Buildbucket for the build status
[08:43:12.788] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:12.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:122:0) after 1m0s
[08:44:12.812] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:122:0)
[08:44:12.812] Timer tick, asking Buildbucket for the build status
[08:44:13.027] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:13.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:123:0) after 1m0s
[08:45:13.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:123:0)
[08:45:13.057] Timer tick, asking Buildbucket for the build status
[08:45:13.154] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:13.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:124:0) after 1m0s
[08:46:13.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:124:0)
[08:46:13.176] Timer tick, asking Buildbucket for the build status
[08:46:13.330] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:13.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:125:0) after 1m0s
[08:47:13.385] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:125:0)
[08:47:13.385] Timer tick, asking Buildbucket for the build status
[08:47:13.460] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:13.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:126:0) after 1m0s
[08:48:13.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:126:0)
[08:48:13.528] Timer tick, asking Buildbucket for the build status
[08:48:13.597] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:13.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:127:0) after 1m0s
[08:49:13.618] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:127:0)
[08:49:13.618] Timer tick, asking Buildbucket for the build status
[08:49:13.883] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:13.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:128:0) after 1m0s
[08:50:13.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:128:0)
[08:50:13.962] Timer tick, asking Buildbucket for the build status
[08:50:14.027] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:14.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:129:0) after 1m0s
[08:51:14.092] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:129:0)
[08:51:14.092] Timer tick, asking Buildbucket for the build status
[08:51:14.174] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:14.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:130:0) after 1m0s
[08:52:14.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:130:0)
[08:52:14.258] Timer tick, asking Buildbucket for the build status
[08:52:14.360] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:14.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:131:0) after 1m0s
[08:53:14.445] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:131:0)
[08:53:14.445] Timer tick, asking Buildbucket for the build status
[08:53:14.979] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:14.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:132:0) after 1m0s
[08:54:15.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:132:0)
[08:54:15.096] Timer tick, asking Buildbucket for the build status
[08:54:15.356] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:15.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:133:0) after 1m0s
[08:55:15.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:133:0)
[08:55:15.376] Timer tick, asking Buildbucket for the build status
[08:55:15.429] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:15.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:134:0) after 1m0s
[08:56:15.502] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:134:0)
[08:56:15.502] Timer tick, asking Buildbucket for the build status
[08:56:15.640] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:15.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:135:0) after 1m0s
[08:57:15.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:135:0)
[08:57:15.678] Timer tick, asking Buildbucket for the build status
[08:57:16.043] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:16.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:136:0) after 1m0s
[08:58:16.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:136:0)
[08:58:16.065] Timer tick, asking Buildbucket for the build status
[08:58:16.222] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:16.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:137:0) after 1m0s
[08:59:16.242] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:137:0)
[08:59:16.242] Timer tick, asking Buildbucket for the build status
[08:59:16.362] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:16.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:138:0) after 1m0s
[09:00:16.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:138:0)
[09:00:16.399] Timer tick, asking Buildbucket for the build status
[09:00:16.466] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:16.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:139:0) after 1m0s
[09:01:16.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:139:0)
[09:01:16.490] Timer tick, asking Buildbucket for the build status
[09:01:16.600] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:16.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:140:0) after 1m0s
[09:02:16.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:140:0)
[09:02:16.645] Timer tick, asking Buildbucket for the build status
[09:02:16.763] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:16.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:141:0) after 1m0s
[09:03:16.937] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:141:0)
[09:03:16.937] Timer tick, asking Buildbucket for the build status
[09:03:17.034] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:17.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:142:0) after 1m0s
[09:04:17.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:142:0)
[09:04:17.033] Timer tick, asking Buildbucket for the build status
[09:04:17.487] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:17.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:143:0) after 1m0s
[09:05:17.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:143:0)
[09:05:17.657] Timer tick, asking Buildbucket for the build status
[09:05:17.717] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:17.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:144:0) after 1m0s
[09:06:17.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:144:0)
[09:06:17.837] Timer tick, asking Buildbucket for the build status
[09:06:17.901] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:17.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:145:0) after 1m0s
[09:07:17.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:145:0)
[09:07:17.923] Timer tick, asking Buildbucket for the build status
[09:07:18.017] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:18.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:146:0) after 1m0s
[09:08:18.091] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:146:0)
[09:08:18.091] Timer tick, asking Buildbucket for the build status
[09:08:18.591] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:18.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:147:0) after 1m0s
[09:09:18.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:147:0)
[09:09:18.816] Timer tick, asking Buildbucket for the build status
[09:09:19.038] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:19.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:148:0) after 1m0s
[09:10:19.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:148:0)
[09:10:19.074] Timer tick, asking Buildbucket for the build status
[09:10:19.135] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:19.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:149:0) after 1m0s
[09:11:19.359] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:149:0)
[09:11:19.359] Timer tick, asking Buildbucket for the build status
[09:11:19.413] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:19.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:150:0) after 1m0s
[09:12:19.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:150:0)
[09:12:19.521] Timer tick, asking Buildbucket for the build status
[09:12:19.910] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:19.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:151:0) after 1m0s
[09:13:20.095] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:151:0)
[09:13:20.095] Timer tick, asking Buildbucket for the build status
[09:13:20.161] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:20.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:152:0) after 1m0s
[09:14:20.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:152:0)
[09:14:20.187] Timer tick, asking Buildbucket for the build status
[09:14:20.859] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:20.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:153:0) after 1m0s
[09:15:21.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:153:0)
[09:15:21.005] Timer tick, asking Buildbucket for the build status
[09:15:21.066] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:21.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:154:0) after 1m0s
[09:16:21.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:154:0)
[09:16:21.113] Timer tick, asking Buildbucket for the build status
[09:16:21.237] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:21.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:155:0) after 1m0s
[09:17:21.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:155:0)
[09:17:21.350] Timer tick, asking Buildbucket for the build status
[09:17:21.598] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:21.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:156:0) after 1m0s
[09:18:21.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:156:0)
[09:18:21.693] Timer tick, asking Buildbucket for the build status
[09:18:21.942] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:21.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:157:0) after 1m0s
[09:19:21.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:157:0)
[09:19:21.985] Timer tick, asking Buildbucket for the build status
[09:19:22.391] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:22.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:158:0) after 1m0s
[09:20:22.415] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:158:0)
[09:20:22.415] Timer tick, asking Buildbucket for the build status
[09:20:22.475] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:22.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:159:0) after 1m0s
[09:21:22.497] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:159:0)
[09:21:22.497] Timer tick, asking Buildbucket for the build status
[09:21:22.554] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:22.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:160:0) after 1m0s
[09:22:22.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:160:0)
[09:22:22.574] Timer tick, asking Buildbucket for the build status
[09:22:22.644] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:22.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:161:0) after 1m0s
[09:23:22.627] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:161:0)
[09:23:22.627] Timer tick, asking Buildbucket for the build status
[09:23:22.735] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:22.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:162:0) after 1m0s
[09:24:22.763] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:162:0)
[09:24:22.763] Timer tick, asking Buildbucket for the build status
[09:24:23.156] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:23.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:163:0) after 1m0s
[09:25:23.192] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:163:0)
[09:25:23.192] Timer tick, asking Buildbucket for the build status
[09:25:23.267] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:23.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:164:0) after 1m0s
[09:26:23.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:164:0)
[09:26:23.286] Timer tick, asking Buildbucket for the build status
[09:26:23.351] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:23.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:165:0) after 1m0s
[09:27:23.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:165:0)
[09:27:23.474] Timer tick, asking Buildbucket for the build status
[09:27:23.598] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:23.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:166:0) after 1m0s
[09:28:23.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:166:0)
[09:28:23.655] Timer tick, asking Buildbucket for the build status
[09:28:23.886] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:23.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:167:0) after 1m0s
[09:29:23.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:167:0)
[09:29:23.909] Timer tick, asking Buildbucket for the build status
[09:29:24.042] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:24.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:168:0) after 1m0s
[09:30:24.056] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:168:0)
[09:30:24.056] Timer tick, asking Buildbucket for the build status
[09:30:24.126] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:24.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:169:0) after 1m0s
[09:31:24.154] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:169:0)
[09:31:24.154] Timer tick, asking Buildbucket for the build status
[09:31:24.506] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:24.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:170:0) after 1m0s
[09:32:24.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:170:0)
[09:32:24.555] Timer tick, asking Buildbucket for the build status
[09:32:24.639] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:24.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:171:0) after 1m0s
[09:33:24.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:171:0)
[09:33:24.687] Timer tick, asking Buildbucket for the build status
[09:33:24.841] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:24.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:172:0) after 1m0s
[09:34:24.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:172:0)
[09:34:24.875] Timer tick, asking Buildbucket for the build status
[09:34:24.963] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:24.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:173:0) after 1m0s
[09:35:24.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:173:0)
[09:35:24.989] Timer tick, asking Buildbucket for the build status
[09:35:25.472] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:25.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:174:0) after 1m0s
[09:36:25.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:174:0)
[09:36:25.522] Timer tick, asking Buildbucket for the build status
[09:36:25.611] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:25.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:175:0) after 1m0s
[09:37:25.683] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:175:0)
[09:37:25.683] Timer tick, asking Buildbucket for the build status
[09:37:25.785] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:25.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:176:0) after 1m0s
[09:38:25.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:176:0)
[09:38:25.843] Timer tick, asking Buildbucket for the build status
[09:38:26.137] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:26.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:177:0) after 1m0s
[09:39:26.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:177:0)
[09:39:26.212] Timer tick, asking Buildbucket for the build status
[09:39:26.310] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:26.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:178:0) after 1m0s
[09:40:26.492] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:178:0)
[09:40:26.492] Timer tick, asking Buildbucket for the build status
[09:40:26.626] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:26.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:179:0) after 1m0s
[09:41:26.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:179:0)
[09:41:26.750] Timer tick, asking Buildbucket for the build status
[09:41:26.822] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:26.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:180:0) after 1m0s
[09:42:26.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:180:0)
[09:42:26.957] Timer tick, asking Buildbucket for the build status
[09:42:27.154] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:27.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:181:0) after 1m0s
[09:43:27.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:181:0)
[09:43:27.201] Timer tick, asking Buildbucket for the build status
[09:43:27.273] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:27.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:182:0) after 1m0s
[09:44:27.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:182:0)
[09:44:27.347] Timer tick, asking Buildbucket for the build status
[09:44:27.441] Build 8855975938552695792: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:27.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021406000543962368:183:0) after 1m0s
[09:44:31.847] Received PubSub notification, asking Buildbucket for the build status
[09:44:31.953] Build 8855975938552695792: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:44:31.953] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612691070990821",
"created_by": "project:chromium",
"created_ts": "1612680222898490",
"id": "8855975938552695792",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"685c8b859043994eff82974a3e0e5a3cc0d31a9f\"}}",
"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\": \"685c8b859043994eff82974a3e0e5a3cc0d31a9f\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-9-qwrj\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1555, \"got_angle_revision\": \"580961fbb01984d2d9b2f382609f9fc0c44e6b82\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"685c8b859043994eff82974a3e0e5a3cc0d31a9f\", \"got_revision_cp\": \"refs/heads/master@{#851520}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"d42413a4b4636422ba5ea66ba40e7d24fb64f9b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33183}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"685c8b859043994eff82974a3e0e5a3cc0d31a9f\"}, \"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-9-qwrj\"], \"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\": [\"5676-c76598c\"], \"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": "1612680447032787",
"status": "COMPLETED",
"status_changed_ts": "1612691071402005",
"tags": [
"build_address:luci.chromium.ci/win-official/1555",
"builder:win-official",
"buildset:commit/git/685c8b859043994eff82974a3e0e5a3cc0d31a9f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/685c8b859043994eff82974a3e0e5a3cc0d31a9f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021406000543962368",
"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/8855975938552695792/+/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:51940ce4a505fe10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612691071406008",
"url": "https://ci.chromium.org/b/8855975938552695792",
"utcnow_ts": "1612691071943242"
}
}
[09:44:31.953] Invocation finished in 3h0m50.338354392s with status SUCCEEDED