[10:18:42.592] New invocation is queued and will start shortly
[10:18:43.785] Starting the invocation (attempt 1)
[10:18:43.883] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:18:43.884] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:18:43.884] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9031358139213513712",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f6b345650b875e75148497ccab51f2c85f9bf6d5\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9031358139213513712",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/f6b345650b875e75148497ccab51f2c85f9bf6d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f6b345650b875e75148497ccab51f2c85f9bf6d5",
"gitiles_ref:refs/heads/master"
]
}
[10:18:44.519] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1603189123969159",
"id": "8865928077103091264",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f6b345650b875e75148497ccab51f2c85f9bf6d5\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1603189124389543",
"tags": [
"build_address:luci.chromium.ci/win-official/859",
"builder:win-official",
"buildset:commit/git/f6b345650b875e75148497ccab51f2c85f9bf6d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f6b345650b875e75148497ccab51f2c85f9bf6d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031358139213513712",
"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/8865928077103091264/+/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": "1603189124389699",
"url": "https://ci.chromium.org/b/8865928077103091264",
"utcnow_ts": "1603189124511217"
}
}
[10:18:44.519] Task URL: https://ci.chromium.org/b/8865928077103091264
[10:18:44.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:2:0) after 1m0s
[10:19:35.468] Received PubSub notification, asking Buildbucket for the build status
[10:19:35.542] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:44.543] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:2:0)
[10:19:44.543] Timer tick, asking Buildbucket for the build status
[10:19:44.822] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:44.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:4:0) after 1m0s
[10:20:44.907] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:4:0)
[10:20:44.907] Timer tick, asking Buildbucket for the build status
[10:20:45.056] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:45.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:5:0) after 1m0s
[10:21:45.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:5:0)
[10:21:45.079] Timer tick, asking Buildbucket for the build status
[10:21:45.444] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:45.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:6:0) after 1m0s
[10:22:45.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:6:0)
[10:22:45.576] Timer tick, asking Buildbucket for the build status
[10:22:45.660] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:45.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:7:0) after 1m0s
[10:23:45.781] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:7:0)
[10:23:45.781] Timer tick, asking Buildbucket for the build status
[10:23:45.940] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:45.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:8:0) after 1m0s
[10:24:45.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:8:0)
[10:24:45.938] Timer tick, asking Buildbucket for the build status
[10:24:46.031] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:46.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:9:0) after 1m0s
[10:25:46.091] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:9:0)
[10:25:46.091] Timer tick, asking Buildbucket for the build status
[10:25:46.313] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:46.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:10:0) after 1m0s
[10:26:46.328] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:10:0)
[10:26:46.329] Timer tick, asking Buildbucket for the build status
[10:26:46.443] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:46.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:11:0) after 1m0s
[10:27:46.512] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:11:0)
[10:27:46.512] Timer tick, asking Buildbucket for the build status
[10:27:46.705] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:46.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:12:0) after 1m0s
[10:28:46.689] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:12:0)
[10:28:46.689] Timer tick, asking Buildbucket for the build status
[10:28:47.080] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:47.080] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:13:0) after 1m0s
[10:29:47.203] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:13:0)
[10:29:47.203] Timer tick, asking Buildbucket for the build status
[10:29:47.271] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:47.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:14:0) after 1m0s
[10:30:47.356] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:14:0)
[10:30:47.356] Timer tick, asking Buildbucket for the build status
[10:30:47.930] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:47.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:15:0) after 1m0s
[10:31:47.991] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:15:0)
[10:31:47.991] Timer tick, asking Buildbucket for the build status
[10:31:48.295] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:48.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:16:0) after 1m0s
[10:32:48.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:16:0)
[10:32:48.317] Timer tick, asking Buildbucket for the build status
[10:32:48.401] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:48.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:17:0) after 1m0s
[10:33:48.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:17:0)
[10:33:48.565] Timer tick, asking Buildbucket for the build status
[10:33:48.862] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:48.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:18:0) after 1m0s
[10:34:48.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:18:0)
[10:34:48.889] Timer tick, asking Buildbucket for the build status
[10:34:49.207] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:49.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:19:0) after 1m0s
[10:35:49.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:19:0)
[10:35:49.298] Timer tick, asking Buildbucket for the build status
[10:35:49.356] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:49.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:20:0) after 1m0s
[10:36:49.509] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:20:0)
[10:36:49.509] Timer tick, asking Buildbucket for the build status
[10:36:49.672] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:49.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:21:0) after 1m0s
[10:37:49.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:21:0)
[10:37:49.814] Timer tick, asking Buildbucket for the build status
[10:37:49.885] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:49.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:22:0) after 1m0s
[10:38:50.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:22:0)
[10:38:50.130] Timer tick, asking Buildbucket for the build status
[10:38:50.275] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:50.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:23:0) after 1m0s
[10:39:50.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:23:0)
[10:39:50.620] Timer tick, asking Buildbucket for the build status
[10:39:50.691] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:50.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:24:0) after 1m0s
[10:40:50.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:24:0)
[10:40:50.779] Timer tick, asking Buildbucket for the build status
[10:40:50.953] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:50.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:25:0) after 1m0s
[10:41:50.987] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:25:0)
[10:41:50.987] Timer tick, asking Buildbucket for the build status
[10:41:51.094] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:51.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:26:0) after 1m0s
[10:42:51.140] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:26:0)
[10:42:51.140] Timer tick, asking Buildbucket for the build status
[10:42:51.246] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:51.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:27:0) after 1m0s
[10:43:51.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:27:0)
[10:43:51.340] Timer tick, asking Buildbucket for the build status
[10:43:51.677] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:51.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:28:0) after 1m0s
[10:44:51.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:28:0)
[10:44:51.852] Timer tick, asking Buildbucket for the build status
[10:44:51.916] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:51.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:29:0) after 1m0s
[10:45:52.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:29:0)
[10:45:52.230] Timer tick, asking Buildbucket for the build status
[10:45:52.406] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:52.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:30:0) after 1m0s
[10:46:52.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:30:0)
[10:46:52.524] Timer tick, asking Buildbucket for the build status
[10:46:52.596] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:52.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:31:0) after 1m0s
[10:47:52.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:31:0)
[10:47:52.767] Timer tick, asking Buildbucket for the build status
[10:47:52.839] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:52.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:32:0) after 1m0s
[10:48:53.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:32:0)
[10:48:53.064] Timer tick, asking Buildbucket for the build status
[10:48:53.191] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:53.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:33:0) after 1m0s
[10:49:53.482] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:33:0)
[10:49:53.482] Timer tick, asking Buildbucket for the build status
[10:49:53.573] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:53.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:34:0) after 1m0s
[10:50:53.925] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:34:0)
[10:50:53.925] Timer tick, asking Buildbucket for the build status
[10:50:53.991] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:53.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:35:0) after 1m0s
[10:51:54.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:35:0)
[10:51:54.144] Timer tick, asking Buildbucket for the build status
[10:51:54.288] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:54.288] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:36:0) after 1m0s
[10:52:54.597] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:36:0)
[10:52:54.597] Timer tick, asking Buildbucket for the build status
[10:52:54.707] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:54.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:37:0) after 1m0s
[10:53:54.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:37:0)
[10:53:54.795] Timer tick, asking Buildbucket for the build status
[10:53:54.941] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:54.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:38:0) after 1m0s
[10:54:56.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:38:0)
[10:54:56.228] Timer tick, asking Buildbucket for the build status
[10:54:56.357] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:56.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:39:0) after 1m0s
[10:55:56.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:39:0)
[10:55:56.699] Timer tick, asking Buildbucket for the build status
[10:55:56.798] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:56.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:40:0) after 1m0s
[10:56:56.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:40:0)
[10:56:56.939] Timer tick, asking Buildbucket for the build status
[10:56:57.002] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:57.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:41:0) after 1m0s
[10:57:57.106] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:41:0)
[10:57:57.106] Timer tick, asking Buildbucket for the build status
[10:57:57.416] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:57.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:42:0) after 1m0s
[10:58:57.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:42:0)
[10:58:57.419] Timer tick, asking Buildbucket for the build status
[10:58:57.892] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:57.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:43:0) after 1m0s
[10:59:57.904] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:43:0)
[10:59:57.904] Timer tick, asking Buildbucket for the build status
[10:59:57.998] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:57.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:44:0) after 1m0s
[11:00:58.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:44:0)
[11:00:58.087] Timer tick, asking Buildbucket for the build status
[11:00:58.309] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:58.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:45:0) after 1m0s
[11:01:58.380] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:45:0)
[11:01:58.380] Timer tick, asking Buildbucket for the build status
[11:01:58.476] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:58.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:46:0) after 1m0s
[11:02:58.516] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:46:0)
[11:02:58.516] Timer tick, asking Buildbucket for the build status
[11:02:58.803] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:58.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:47:0) after 1m0s
[11:03:58.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:47:0)
[11:03:58.886] Timer tick, asking Buildbucket for the build status
[11:03:59.038] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:59.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:48:0) after 1m0s
[11:04:59.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:48:0)
[11:04:59.074] Timer tick, asking Buildbucket for the build status
[11:04:59.138] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:59.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:49:0) after 1m0s
[11:05:59.224] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:49:0)
[11:05:59.224] Timer tick, asking Buildbucket for the build status
[11:05:59.399] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:59.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:50:0) after 1m0s
[11:06:59.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:50:0)
[11:06:59.603] Timer tick, asking Buildbucket for the build status
[11:06:59.660] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:59.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:51:0) after 1m0s
[11:07:59.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:51:0)
[11:07:59.953] Timer tick, asking Buildbucket for the build status
[11:08:00.166] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:00.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:52:0) after 1m0s
[11:09:00.240] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:52:0)
[11:09:00.240] Timer tick, asking Buildbucket for the build status
[11:09:00.475] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:00.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:53:0) after 1m0s
[11:10:00.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:53:0)
[11:10:00.820] Timer tick, asking Buildbucket for the build status
[11:10:00.884] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:00.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:54:0) after 1m0s
[11:11:00.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:54:0)
[11:11:00.906] Timer tick, asking Buildbucket for the build status
[11:11:01.083] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:01.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:55:0) after 1m0s
[11:12:02.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:55:0)
[11:12:02.414] Timer tick, asking Buildbucket for the build status
[11:12:02.484] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:02.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:56:0) after 1m0s
[11:13:02.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:56:0)
[11:13:02.686] Timer tick, asking Buildbucket for the build status
[11:13:02.898] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:02.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:57:0) after 1m0s
[11:14:03.586] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:57:0)
[11:14:03.586] Timer tick, asking Buildbucket for the build status
[11:14:04.066] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:04.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:58:0) after 1m0s
[11:15:04.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:58:0)
[11:15:04.407] Timer tick, asking Buildbucket for the build status
[11:15:04.481] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:04.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:59:0) after 1m0s
[11:16:04.715] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:59:0)
[11:16:04.715] Timer tick, asking Buildbucket for the build status
[11:16:04.780] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:04.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:60:0) after 1m0s
[11:17:04.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:60:0)
[11:17:04.854] Timer tick, asking Buildbucket for the build status
[11:17:05.008] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:05.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:61:0) after 1m0s
[11:18:05.050] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:61:0)
[11:18:05.050] Timer tick, asking Buildbucket for the build status
[11:18:05.117] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:05.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:62:0) after 1m0s
[11:19:05.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:62:0)
[11:19:05.173] Timer tick, asking Buildbucket for the build status
[11:19:05.372] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:05.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:63:0) after 1m0s
[11:20:05.548] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:63:0)
[11:20:05.548] Timer tick, asking Buildbucket for the build status
[11:20:05.684] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:05.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:64:0) after 1m0s
[11:21:05.997] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:64:0)
[11:21:05.997] Timer tick, asking Buildbucket for the build status
[11:21:07.818] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:07.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:65:0) after 1m0s
[11:22:08.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:65:0)
[11:22:08.319] Timer tick, asking Buildbucket for the build status
[11:22:08.384] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:08.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:66:0) after 1m0s
[11:23:08.427] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:66:0)
[11:23:08.427] Timer tick, asking Buildbucket for the build status
[11:23:08.491] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:08.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:67:0) after 1m0s
[11:24:08.566] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:67:0)
[11:24:08.566] Timer tick, asking Buildbucket for the build status
[11:24:08.712] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:08.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:68:0) after 1m0s
[11:25:08.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:68:0)
[11:25:08.739] Timer tick, asking Buildbucket for the build status
[11:25:09.102] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:09.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:69:0) after 1m0s
[11:26:09.127] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:69:0)
[11:26:09.127] Timer tick, asking Buildbucket for the build status
[11:26:09.253] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:09.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:70:0) after 1m0s
[11:27:09.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:70:0)
[11:27:09.239] Timer tick, asking Buildbucket for the build status
[11:27:09.598] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:09.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:71:0) after 1m0s
[11:28:09.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:71:0)
[11:28:09.703] Timer tick, asking Buildbucket for the build status
[11:28:09.773] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:09.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:72:0) after 1m0s
[11:29:09.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:72:0)
[11:29:09.817] Timer tick, asking Buildbucket for the build status
[11:29:09.932] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:09.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:73:0) after 1m0s
[11:30:10.042] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:73:0)
[11:30:10.042] Timer tick, asking Buildbucket for the build status
[11:30:10.266] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:10.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:74:0) after 1m0s
[11:31:10.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:74:0)
[11:31:10.295] Timer tick, asking Buildbucket for the build status
[11:31:10.348] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:10.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:75:0) after 1m0s
[11:32:10.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:75:0)
[11:32:10.388] Timer tick, asking Buildbucket for the build status
[11:32:10.481] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:10.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:76:0) after 1m0s
[11:33:10.504] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:76:0)
[11:33:10.504] Timer tick, asking Buildbucket for the build status
[11:33:10.577] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:10.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:77:0) after 1m0s
[11:34:10.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:77:0)
[11:34:10.616] Timer tick, asking Buildbucket for the build status
[11:34:10.758] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:10.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:78:0) after 1m0s
[11:35:10.799] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:78:0)
[11:35:10.799] Timer tick, asking Buildbucket for the build status
[11:35:10.864] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:10.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:79:0) after 1m0s
[11:36:10.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:79:0)
[11:36:10.900] Timer tick, asking Buildbucket for the build status
[11:36:11.203] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:11.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:80:0) after 1m0s
[11:37:11.232] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:80:0)
[11:37:11.232] Timer tick, asking Buildbucket for the build status
[11:37:11.310] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:11.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:81:0) after 1m0s
[11:38:11.408] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:81:0)
[11:38:11.408] Timer tick, asking Buildbucket for the build status
[11:38:11.512] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:11.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:82:0) after 1m0s
[11:39:11.605] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:82:0)
[11:39:11.605] Timer tick, asking Buildbucket for the build status
[11:39:11.707] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:11.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:83:0) after 1m0s
[11:40:11.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:83:0)
[11:40:11.839] Timer tick, asking Buildbucket for the build status
[11:40:11.894] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:11.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:84:0) after 1m0s
[11:41:12.030] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:84:0)
[11:41:12.030] Timer tick, asking Buildbucket for the build status
[11:41:12.106] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:12.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:85:0) after 1m0s
[11:42:12.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:85:0)
[11:42:12.177] Timer tick, asking Buildbucket for the build status
[11:42:12.251] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:12.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:86:0) after 1m0s
[11:43:12.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:86:0)
[11:43:12.309] Timer tick, asking Buildbucket for the build status
[11:43:12.380] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:12.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:87:0) after 1m0s
[11:44:12.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:87:0)
[11:44:12.535] Timer tick, asking Buildbucket for the build status
[11:44:12.644] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:12.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:88:0) after 1m0s
[11:45:12.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:88:0)
[11:45:12.732] Timer tick, asking Buildbucket for the build status
[11:45:12.800] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:12.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:89:0) after 1m0s
[11:46:13.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:89:0)
[11:46:13.116] Timer tick, asking Buildbucket for the build status
[11:46:13.176] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:13.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:90:0) after 1m0s
[11:47:13.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:90:0)
[11:47:13.395] Timer tick, asking Buildbucket for the build status
[11:47:13.517] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:13.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:91:0) after 1m0s
[11:48:13.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:91:0)
[11:48:13.959] Timer tick, asking Buildbucket for the build status
[11:48:14.287] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:14.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:92:0) after 1m0s
[11:49:14.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:92:0)
[11:49:14.419] Timer tick, asking Buildbucket for the build status
[11:49:14.535] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:14.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:93:0) after 1m0s
[11:50:14.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:93:0)
[11:50:14.650] Timer tick, asking Buildbucket for the build status
[11:50:14.986] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:14.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:94:0) after 1m0s
[11:51:15.012] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:94:0)
[11:51:15.012] Timer tick, asking Buildbucket for the build status
[11:51:15.280] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:15.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:95:0) after 1m0s
[11:52:15.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:95:0)
[11:52:15.350] Timer tick, asking Buildbucket for the build status
[11:52:15.441] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:15.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:96:0) after 1m0s
[11:53:15.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:96:0)
[11:53:15.456] Timer tick, asking Buildbucket for the build status
[11:53:15.520] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:15.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:97:0) after 1m0s
[11:54:15.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:97:0)
[11:54:15.607] Timer tick, asking Buildbucket for the build status
[11:54:15.813] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:15.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:98:0) after 1m0s
[11:55:15.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:98:0)
[11:55:15.843] Timer tick, asking Buildbucket for the build status
[11:55:15.912] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:15.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:99:0) after 1m0s
[11:56:15.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:99:0)
[11:56:15.970] Timer tick, asking Buildbucket for the build status
[11:56:16.026] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:16.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:100:0) after 1m0s
[11:57:16.051] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:100:0)
[11:57:16.051] Timer tick, asking Buildbucket for the build status
[11:57:16.122] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:16.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:101:0) after 1m0s
[11:58:16.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:101:0)
[11:58:16.437] Timer tick, asking Buildbucket for the build status
[11:58:16.499] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:16.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:102:0) after 1m0s
[11:59:16.652] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:102:0)
[11:59:16.652] Timer tick, asking Buildbucket for the build status
[11:59:16.760] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:16.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:103:0) after 1m0s
[12:00:16.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:103:0)
[12:00:16.976] Timer tick, asking Buildbucket for the build status
[12:00:17.062] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:17.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:104:0) after 1m0s
[12:01:17.672] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:104:0)
[12:01:17.672] Timer tick, asking Buildbucket for the build status
[12:01:17.737] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:17.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:105:0) after 1m0s
[12:02:17.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:105:0)
[12:02:17.940] Timer tick, asking Buildbucket for the build status
[12:02:17.999] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:17.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:106:0) after 1m0s
[12:03:18.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:106:0)
[12:03:18.281] Timer tick, asking Buildbucket for the build status
[12:03:18.343] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:18.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:107:0) after 1m0s
[12:04:18.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:107:0)
[12:04:18.596] Timer tick, asking Buildbucket for the build status
[12:04:18.799] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:18.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:108:0) after 1m0s
[12:05:21.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:108:0)
[12:05:21.831] Timer tick, asking Buildbucket for the build status
[12:05:21.883] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:21.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:109:0) after 1m0s
[12:06:21.962] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:109:0)
[12:06:21.962] Timer tick, asking Buildbucket for the build status
[12:06:22.202] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:22.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:110:0) after 1m0s
[12:07:22.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:110:0)
[12:07:22.263] Timer tick, asking Buildbucket for the build status
[12:07:22.490] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:22.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:111:0) after 1m0s
[12:08:22.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:111:0)
[12:08:22.600] Timer tick, asking Buildbucket for the build status
[12:08:22.667] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:22.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:112:0) after 1m0s
[12:09:22.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:112:0)
[12:09:22.698] Timer tick, asking Buildbucket for the build status
[12:09:22.840] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:22.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:113:0) after 1m0s
[12:10:22.915] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:113:0)
[12:10:22.915] Timer tick, asking Buildbucket for the build status
[12:10:22.994] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:22.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:114:0) after 1m0s
[12:11:23.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:114:0)
[12:11:23.047] Timer tick, asking Buildbucket for the build status
[12:11:23.143] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:23.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:115:0) after 1m0s
[12:12:23.236] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:115:0)
[12:12:23.236] Timer tick, asking Buildbucket for the build status
[12:12:23.296] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:23.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:116:0) after 1m0s
[12:13:23.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:116:0)
[12:13:23.348] Timer tick, asking Buildbucket for the build status
[12:13:23.421] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:23.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:117:0) after 1m0s
[12:14:23.570] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:117:0)
[12:14:23.570] Timer tick, asking Buildbucket for the build status
[12:14:23.674] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:23.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:118:0) after 1m0s
[12:15:24.029] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:118:0)
[12:15:24.029] Timer tick, asking Buildbucket for the build status
[12:15:24.260] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:24.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:119:0) after 1m0s
[12:16:25.160] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:119:0)
[12:16:25.160] Timer tick, asking Buildbucket for the build status
[12:16:25.382] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:25.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:120:0) after 1m0s
[12:17:25.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:120:0)
[12:17:25.395] Timer tick, asking Buildbucket for the build status
[12:17:25.462] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:25.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:121:0) after 1m0s
[12:18:25.515] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:121:0)
[12:18:25.515] Timer tick, asking Buildbucket for the build status
[12:18:25.688] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:25.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:122:0) after 1m0s
[12:19:25.754] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:122:0)
[12:19:25.755] Timer tick, asking Buildbucket for the build status
[12:19:25.862] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:25.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:123:0) after 1m0s
[12:20:26.000] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:123:0)
[12:20:26.000] Timer tick, asking Buildbucket for the build status
[12:20:26.222] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:26.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:124:0) after 1m0s
[12:21:26.292] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:124:0)
[12:21:26.292] Timer tick, asking Buildbucket for the build status
[12:21:26.392] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:26.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:125:0) after 1m0s
[12:22:26.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:125:0)
[12:22:26.400] Timer tick, asking Buildbucket for the build status
[12:22:26.787] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:26.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:126:0) after 1m0s
[12:23:26.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:126:0)
[12:23:26.817] Timer tick, asking Buildbucket for the build status
[12:23:26.890] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:26.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:127:0) after 1m0s
[12:24:26.944] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:127:0)
[12:24:26.944] Timer tick, asking Buildbucket for the build status
[12:24:27.055] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:27.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:128:0) after 1m0s
[12:25:27.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:128:0)
[12:25:27.130] Timer tick, asking Buildbucket for the build status
[12:25:27.224] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:27.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:129:0) after 1m0s
[12:26:27.251] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:129:0)
[12:26:27.251] Timer tick, asking Buildbucket for the build status
[12:26:27.322] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:27.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:130:0) after 1m0s
[12:27:27.538] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:130:0)
[12:27:27.538] Timer tick, asking Buildbucket for the build status
[12:27:27.608] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:27.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:131:0) after 1m0s
[12:28:27.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:131:0)
[12:28:27.645] Timer tick, asking Buildbucket for the build status
[12:28:27.973] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:27.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:132:0) after 1m0s
[12:29:28.081] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:132:0)
[12:29:28.082] Timer tick, asking Buildbucket for the build status
[12:29:28.197] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:28.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:133:0) after 1m0s
[12:30:28.276] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:133:0)
[12:30:28.276] Timer tick, asking Buildbucket for the build status
[12:30:28.402] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:28.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:134:0) after 1m0s
[12:31:28.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:134:0)
[12:31:28.510] Timer tick, asking Buildbucket for the build status
[12:31:28.720] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:28.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:135:0) after 1m0s
[12:32:28.826] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:135:0)
[12:32:28.826] Timer tick, asking Buildbucket for the build status
[12:32:29.153] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:29.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:136:0) after 1m0s
[12:33:29.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:136:0)
[12:33:29.277] Timer tick, asking Buildbucket for the build status
[12:33:29.341] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:29.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:137:0) after 1m0s
[12:34:29.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:137:0)
[12:34:29.450] Timer tick, asking Buildbucket for the build status
[12:34:29.564] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:29.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:138:0) after 1m0s
[12:35:29.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:138:0)
[12:35:29.657] Timer tick, asking Buildbucket for the build status
[12:35:29.814] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:29.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:139:0) after 1m0s
[12:36:29.989] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:139:0)
[12:36:29.989] Timer tick, asking Buildbucket for the build status
[12:36:30.201] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:30.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:140:0) after 1m0s
[12:37:30.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:140:0)
[12:37:30.266] Timer tick, asking Buildbucket for the build status
[12:37:30.417] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:30.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:141:0) after 1m0s
[12:38:30.525] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:141:0)
[12:38:30.525] Timer tick, asking Buildbucket for the build status
[12:38:30.792] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:30.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:142:0) after 1m0s
[12:39:30.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:142:0)
[12:39:30.850] Timer tick, asking Buildbucket for the build status
[12:39:30.964] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:30.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:143:0) after 1m0s
[12:40:30.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:143:0)
[12:40:30.990] Timer tick, asking Buildbucket for the build status
[12:40:31.108] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:31.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:144:0) after 1m0s
[12:41:31.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:144:0)
[12:41:31.340] Timer tick, asking Buildbucket for the build status
[12:41:31.441] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:31.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:145:0) after 1m0s
[12:42:31.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:145:0)
[12:42:31.603] Timer tick, asking Buildbucket for the build status
[12:42:31.668] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:31.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:146:0) after 1m0s
[12:43:31.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:146:0)
[12:43:31.700] Timer tick, asking Buildbucket for the build status
[12:43:31.882] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:31.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:147:0) after 1m0s
[12:44:31.939] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:147:0)
[12:44:31.939] Timer tick, asking Buildbucket for the build status
[12:44:32.154] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:32.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:148:0) after 1m0s
[12:45:32.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:148:0)
[12:45:32.187] Timer tick, asking Buildbucket for the build status
[12:45:32.392] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:32.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:149:0) after 1m0s
[12:46:32.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:149:0)
[12:46:32.437] Timer tick, asking Buildbucket for the build status
[12:46:32.553] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:32.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:150:0) after 1m0s
[12:47:32.623] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:150:0)
[12:47:32.623] Timer tick, asking Buildbucket for the build status
[12:47:32.707] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:32.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:151:0) after 1m0s
[12:48:32.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:151:0)
[12:48:32.760] Timer tick, asking Buildbucket for the build status
[12:48:32.862] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:32.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:152:0) after 1m0s
[12:49:32.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:152:0)
[12:49:32.909] Timer tick, asking Buildbucket for the build status
[12:49:33.065] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:33.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:153:0) after 1m0s
[12:50:33.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:153:0)
[12:50:33.217] Timer tick, asking Buildbucket for the build status
[12:50:33.356] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:33.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:154:0) after 1m0s
[12:51:33.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:154:0)
[12:51:33.648] Timer tick, asking Buildbucket for the build status
[12:51:33.763] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:33.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:155:0) after 1m0s
[12:52:33.836] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:155:0)
[12:52:33.836] Timer tick, asking Buildbucket for the build status
[12:52:33.926] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:33.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:156:0) after 1m0s
[12:53:34.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:156:0)
[12:53:34.074] Timer tick, asking Buildbucket for the build status
[12:53:34.312] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:34.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:157:0) after 1m0s
[12:54:34.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:157:0)
[12:54:34.357] Timer tick, asking Buildbucket for the build status
[12:54:34.477] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:34.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:158:0) after 1m0s
[12:55:34.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:158:0)
[12:55:34.589] Timer tick, asking Buildbucket for the build status
[12:55:34.707] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:34.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:159:0) after 1m0s
[12:56:34.856] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:159:0)
[12:56:34.856] Timer tick, asking Buildbucket for the build status
[12:56:34.962] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:34.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:160:0) after 1m0s
[12:57:35.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:160:0)
[12:57:35.105] Timer tick, asking Buildbucket for the build status
[12:57:35.176] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:35.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:161:0) after 1m0s
[12:58:35.390] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:161:0)
[12:58:35.390] Timer tick, asking Buildbucket for the build status
[12:58:35.462] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:35.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:162:0) after 1m0s
[12:59:35.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:162:0)
[12:59:35.601] Timer tick, asking Buildbucket for the build status
[12:59:36.163] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:36.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:163:0) after 1m0s
[13:00:36.262] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:163:0)
[13:00:36.262] Timer tick, asking Buildbucket for the build status
[13:00:36.342] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:36.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:164:0) after 1m0s
[13:01:36.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:164:0)
[13:01:36.537] Timer tick, asking Buildbucket for the build status
[13:01:36.597] Build 8865928077103091264: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:36.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9031358139213513712:165:0) after 1m0s
[13:01:58.162] Received PubSub notification, asking Buildbucket for the build status
[13:01:58.317] Build 8865928077103091264: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:01:58.317] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1603198917657646",
"created_by": "project:chromium",
"created_ts": "1603189123969159",
"id": "8865928077103091264",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f6b345650b875e75148497ccab51f2c85f9bf6d5\"}}",
"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\": \"f6b345650b875e75148497ccab51f2c85f9bf6d5\", \"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-8-oskk\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 859, \"got_angle_revision\": \"f0dcaca2c2d842dd6efb4cfe50b0a80473a452e8\", \"got_dawn_revision\": \"c4593127cbdd382c18afea59b242b8040db5e4ce\", \"got_nacl_revision\": \"0fabd6dc4222dfae664e55a84326e0c904f5b62c\", \"got_revision\": \"f6b345650b875e75148497ccab51f2c85f9bf6d5\", \"got_revision_cp\": \"refs/heads/master@{#818847}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"df17a76102dfabb3f1bd6e51449cece9f77b45e3\", \"got_v8_revision\": \"d9768e31d63ec7cf5af6f5f12e16e1a98788e12b\", \"got_v8_revision_cp\": \"refs/heads/8.8.134@{#1}\", \"got_webrtc_revision\": \"d273ed0320fcac7ffd6a083f4fb7871189e3446c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32446}\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f6b345650b875e75148497ccab51f2c85f9bf6d5\"}, \"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-8-oskk\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5601-97d7e7d\"], \"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": "1603189174614020",
"status": "COMPLETED",
"status_changed_ts": "1603198917939285",
"tags": [
"build_address:luci.chromium.ci/win-official/859",
"builder:win-official",
"buildset:commit/git/f6b345650b875e75148497ccab51f2c85f9bf6d5",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f6b345650b875e75148497ccab51f2c85f9bf6d5",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9031358139213513712",
"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/8865928077103091264/+/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:4f5e56360954a810",
"user_agent:luci-scheduler"
],
"updated_ts": "1603198917946597",
"url": "https://ci.chromium.org/b/8865928077103091264",
"utcnow_ts": "1603198918307028"
}
}
[13:01:58.317] Invocation finished in 2h43m15.744034542s with status SUCCEEDED