[06:09:23.072] New invocation is queued and will start shortly
[06:09:29.418] Starting the invocation (attempt 1)
[06:09:29.458] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:09:29.458] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:09:29.459] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039889940250187552",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"5211af9ac644bae721157491efb39f7fbb7e1d7f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9039889940250187552",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/5211af9ac644bae721157491efb39f7fbb7e1d7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5211af9ac644bae721157491efb39f7fbb7e1d7f",
"gitiles_ref:refs/heads/master"
]
}
[06:09:30.291] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1595052569586392",
"id": "8874459872752414240",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5211af9ac644bae721157491efb39f7fbb7e1d7f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595052570118803",
"tags": [
"build_address:luci.chromium.ci/win-official/126",
"builder:win-official",
"buildset:commit/git/5211af9ac644bae721157491efb39f7fbb7e1d7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5211af9ac644bae721157491efb39f7fbb7e1d7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039889940250187552",
"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/8874459872752414240/+/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": "1595052570118945",
"url": "https://ci.chromium.org/b/8874459872752414240",
"utcnow_ts": "1595052570279076"
}
}
[06:09:30.291] Task URL: https://ci.chromium.org/b/8874459872752414240
[06:09:30.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:2:0) after 1m0s
[06:09:35.039] Received PubSub notification, asking Buildbucket for the build status
[06:09:35.136] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:30.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:2:0)
[06:10:30.321] Timer tick, asking Buildbucket for the build status
[06:10:30.408] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:30.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:4:0) after 1m0s
[06:11:30.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:4:0)
[06:11:30.434] Timer tick, asking Buildbucket for the build status
[06:11:30.749] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:30.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:5:0) after 1m0s
[06:12:30.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:5:0)
[06:12:30.830] Timer tick, asking Buildbucket for the build status
[06:12:30.950] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:30.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:6:0) after 1m0s
[06:13:30.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:6:0)
[06:13:30.998] Timer tick, asking Buildbucket for the build status
[06:13:31.333] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:31.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:7:0) after 1m0s
[06:14:31.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:7:0)
[06:14:31.376] Timer tick, asking Buildbucket for the build status
[06:14:31.529] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:31.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:8:0) after 1m0s
[06:15:31.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:8:0)
[06:15:31.519] Timer tick, asking Buildbucket for the build status
[06:15:31.684] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:31.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:9:0) after 1m0s
[06:16:31.716] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:9:0)
[06:16:31.716] Timer tick, asking Buildbucket for the build status
[06:16:32.134] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:32.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:10:0) after 1m0s
[06:17:32.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:10:0)
[06:17:32.109] Timer tick, asking Buildbucket for the build status
[06:17:32.648] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:32.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:11:0) after 1m0s
[06:18:32.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:11:0)
[06:18:32.811] Timer tick, asking Buildbucket for the build status
[06:18:33.003] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:33.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:12:0) after 1m0s
[06:19:33.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:12:0)
[06:19:33.173] Timer tick, asking Buildbucket for the build status
[06:19:33.292] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:33.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:13:0) after 1m0s
[06:20:33.339] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:13:0)
[06:20:33.339] Timer tick, asking Buildbucket for the build status
[06:20:33.491] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:33.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:14:0) after 1m0s
[06:21:33.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:14:0)
[06:21:33.599] Timer tick, asking Buildbucket for the build status
[06:21:33.752] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:33.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:15:0) after 1m0s
[06:22:33.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:15:0)
[06:22:33.853] Timer tick, asking Buildbucket for the build status
[06:22:33.918] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:33.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:16:0) after 1m0s
[06:23:33.959] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:16:0)
[06:23:33.959] Timer tick, asking Buildbucket for the build status
[06:23:34.121] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:34.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:17:0) after 1m0s
[06:24:34.144] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:17:0)
[06:24:34.144] Timer tick, asking Buildbucket for the build status
[06:24:34.368] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:34.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:18:0) after 1m0s
[06:25:34.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:18:0)
[06:25:34.374] Timer tick, asking Buildbucket for the build status
[06:25:34.443] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:34.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:19:0) after 1m0s
[06:26:34.465] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:19:0)
[06:26:34.465] Timer tick, asking Buildbucket for the build status
[06:26:34.865] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:34.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:20:0) after 1m0s
[06:27:34.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:20:0)
[06:27:34.844] Timer tick, asking Buildbucket for the build status
[06:27:35.535] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:35.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:21:0) after 1m0s
[06:28:35.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:21:0)
[06:28:35.571] Timer tick, asking Buildbucket for the build status
[06:28:35.678] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:35.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:22:0) after 1m0s
[06:29:35.752] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:22:0)
[06:29:35.752] Timer tick, asking Buildbucket for the build status
[06:29:36.129] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:36.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:23:0) after 1m0s
[06:30:36.157] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:23:0)
[06:30:36.157] Timer tick, asking Buildbucket for the build status
[06:30:36.712] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:36.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:24:0) after 1m0s
[06:31:36.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:24:0)
[06:31:36.737] Timer tick, asking Buildbucket for the build status
[06:31:37.181] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:37.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:25:0) after 1m0s
[06:32:37.266] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:25:0)
[06:32:37.266] Timer tick, asking Buildbucket for the build status
[06:32:37.605] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:37.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:26:0) after 1m0s
[06:33:37.726] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:26:0)
[06:33:37.726] Timer tick, asking Buildbucket for the build status
[06:33:37.891] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:37.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:27:0) after 1m0s
[06:34:38.143] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:27:0)
[06:34:38.143] Timer tick, asking Buildbucket for the build status
[06:34:38.416] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:38.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:28:0) after 1m0s
[06:35:38.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:28:0)
[06:35:38.441] Timer tick, asking Buildbucket for the build status
[06:35:38.571] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:38.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:29:0) after 1m0s
[06:36:38.592] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:29:0)
[06:36:38.592] Timer tick, asking Buildbucket for the build status
[06:36:38.733] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:38.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:30:0) after 1m0s
[06:37:38.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:30:0)
[06:37:38.819] Timer tick, asking Buildbucket for the build status
[06:37:38.923] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:38.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:31:0) after 1m0s
[06:38:38.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:31:0)
[06:38:38.957] Timer tick, asking Buildbucket for the build status
[06:38:39.154] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:39.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:32:0) after 1m0s
[06:39:39.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:32:0)
[06:39:39.426] Timer tick, asking Buildbucket for the build status
[06:39:39.576] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:39.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:33:0) after 1m0s
[06:40:40.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:33:0)
[06:40:40.553] Timer tick, asking Buildbucket for the build status
[06:40:40.701] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:40.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:34:0) after 1m0s
[06:41:40.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:34:0)
[06:41:40.870] Timer tick, asking Buildbucket for the build status
[06:41:41.122] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:41.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:35:0) after 1m0s
[06:42:41.284] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:35:0)
[06:42:41.284] Timer tick, asking Buildbucket for the build status
[06:42:41.578] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:41.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:36:0) after 1m0s
[06:43:41.820] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:36:0)
[06:43:41.820] Timer tick, asking Buildbucket for the build status
[06:43:41.892] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:41.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:37:0) after 1m0s
[06:44:41.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:37:0)
[06:44:41.990] Timer tick, asking Buildbucket for the build status
[06:44:42.156] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:42.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:38:0) after 1m0s
[06:45:42.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:38:0)
[06:45:42.233] Timer tick, asking Buildbucket for the build status
[06:45:42.493] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:42.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:39:0) after 1m0s
[06:46:42.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:39:0)
[06:46:42.693] Timer tick, asking Buildbucket for the build status
[06:46:42.856] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:42.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:40:0) after 1m0s
[06:47:43.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:40:0)
[06:47:43.017] Timer tick, asking Buildbucket for the build status
[06:47:43.154] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:43.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:41:0) after 1m0s
[06:48:43.463] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:41:0)
[06:48:43.463] Timer tick, asking Buildbucket for the build status
[06:48:43.637] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:43.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:42:0) after 1m0s
[06:49:43.660] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:42:0)
[06:49:43.660] Timer tick, asking Buildbucket for the build status
[06:49:43.778] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:43.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:43:0) after 1m0s
[06:50:43.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:43:0)
[06:50:43.940] Timer tick, asking Buildbucket for the build status
[06:50:44.201] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:44.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:44:0) after 1m0s
[06:51:44.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:44:0)
[06:51:44.216] Timer tick, asking Buildbucket for the build status
[06:51:44.363] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:44.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:45:0) after 1m0s
[06:52:44.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:45:0)
[06:52:44.475] Timer tick, asking Buildbucket for the build status
[06:52:44.649] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:44.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:46:0) after 1m0s
[06:53:44.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:46:0)
[06:53:44.734] Timer tick, asking Buildbucket for the build status
[06:53:44.886] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:44.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:47:0) after 1m0s
[06:54:44.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:47:0)
[06:54:44.913] Timer tick, asking Buildbucket for the build status
[06:54:45.186] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:45.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:48:0) after 1m0s
[06:55:45.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:48:0)
[06:55:45.228] Timer tick, asking Buildbucket for the build status
[06:55:45.527] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:45.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:49:0) after 1m0s
[06:56:45.550] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:49:0)
[06:56:45.550] Timer tick, asking Buildbucket for the build status
[06:56:45.660] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:45.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:50:0) after 1m0s
[06:57:45.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:50:0)
[06:57:45.694] Timer tick, asking Buildbucket for the build status
[06:57:46.181] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:46.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:51:0) after 1m0s
[06:58:46.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:51:0)
[06:58:46.221] Timer tick, asking Buildbucket for the build status
[06:58:46.592] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:46.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:52:0) after 1m0s
[06:59:46.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:52:0)
[06:59:46.723] Timer tick, asking Buildbucket for the build status
[06:59:46.838] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:46.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:53:0) after 1m0s
[07:00:46.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:53:0)
[07:00:46.998] Timer tick, asking Buildbucket for the build status
[07:00:47.209] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:47.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:54:0) after 1m0s
[07:01:47.387] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:54:0)
[07:01:47.387] Timer tick, asking Buildbucket for the build status
[07:01:47.530] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:47.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:55:0) after 1m0s
[07:02:47.724] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:55:0)
[07:02:47.724] Timer tick, asking Buildbucket for the build status
[07:02:48.162] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:48.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:56:0) after 1m0s
[07:03:48.320] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:56:0)
[07:03:48.320] Timer tick, asking Buildbucket for the build status
[07:03:48.553] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:48.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:57:0) after 1m0s
[07:04:48.710] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:57:0)
[07:04:48.710] Timer tick, asking Buildbucket for the build status
[07:04:48.881] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:48.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:58:0) after 1m0s
[07:05:48.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:58:0)
[07:05:48.946] Timer tick, asking Buildbucket for the build status
[07:05:49.278] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:49.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:59:0) after 1m0s
[07:06:49.393] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:59:0)
[07:06:49.393] Timer tick, asking Buildbucket for the build status
[07:06:49.840] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:49.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:60:0) after 1m0s
[07:07:49.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:60:0)
[07:07:49.863] Timer tick, asking Buildbucket for the build status
[07:07:50.384] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:50.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:61:0) after 1m0s
[07:08:50.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:61:0)
[07:08:50.499] Timer tick, asking Buildbucket for the build status
[07:08:50.803] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:50.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:62:0) after 1m0s
[07:09:50.920] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:62:0)
[07:09:50.920] Timer tick, asking Buildbucket for the build status
[07:09:51.144] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:51.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:63:0) after 1m0s
[07:10:51.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:63:0)
[07:10:51.178] Timer tick, asking Buildbucket for the build status
[07:10:51.252] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:51.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:64:0) after 1m0s
[07:11:51.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:64:0)
[07:11:51.345] Timer tick, asking Buildbucket for the build status
[07:11:51.498] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:51.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:65:0) after 1m0s
[07:12:51.630] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:65:0)
[07:12:51.630] Timer tick, asking Buildbucket for the build status
[07:12:51.778] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:51.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:66:0) after 1m0s
[07:13:51.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:66:0)
[07:13:51.780] Timer tick, asking Buildbucket for the build status
[07:13:51.874] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:51.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:67:0) after 1m0s
[07:14:51.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:67:0)
[07:14:51.954] Timer tick, asking Buildbucket for the build status
[07:14:52.364] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:52.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:68:0) after 1m0s
[07:15:52.421] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:68:0)
[07:15:52.421] Timer tick, asking Buildbucket for the build status
[07:15:53.167] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:53.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:69:0) after 1m0s
[07:16:53.187] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:69:0)
[07:16:53.187] Timer tick, asking Buildbucket for the build status
[07:16:53.259] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:53.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:70:0) after 1m0s
[07:17:53.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:70:0)
[07:17:53.283] Timer tick, asking Buildbucket for the build status
[07:17:53.652] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:53.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:71:0) after 1m0s
[07:18:53.674] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:71:0)
[07:18:53.674] Timer tick, asking Buildbucket for the build status
[07:18:53.832] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:53.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:72:0) after 1m0s
[07:19:53.847] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:72:0)
[07:19:53.847] Timer tick, asking Buildbucket for the build status
[07:19:53.914] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:53.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:73:0) after 1m0s
[07:20:54.021] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:73:0)
[07:20:54.021] Timer tick, asking Buildbucket for the build status
[07:20:54.400] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:54.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:74:0) after 1m0s
[07:21:54.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:74:0)
[07:21:54.429] Timer tick, asking Buildbucket for the build status
[07:21:54.657] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:54.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:75:0) after 1m0s
[07:22:54.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:75:0)
[07:22:54.703] Timer tick, asking Buildbucket for the build status
[07:22:54.780] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:54.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:76:0) after 1m0s
[07:23:54.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:76:0)
[07:23:54.843] Timer tick, asking Buildbucket for the build status
[07:23:55.095] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:55.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:77:0) after 1m0s
[07:24:55.071] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:77:0)
[07:24:55.071] Timer tick, asking Buildbucket for the build status
[07:24:55.195] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:55.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:78:0) after 1m0s
[07:25:55.340] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:78:0)
[07:25:55.340] Timer tick, asking Buildbucket for the build status
[07:25:55.421] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:55.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:79:0) after 1m0s
[07:26:55.522] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:79:0)
[07:26:55.522] Timer tick, asking Buildbucket for the build status
[07:26:56.416] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:56.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:80:0) after 1m0s
[07:27:56.546] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:80:0)
[07:27:56.546] Timer tick, asking Buildbucket for the build status
[07:27:57.247] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:57.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:81:0) after 1m0s
[07:28:57.305] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:81:0)
[07:28:57.305] Timer tick, asking Buildbucket for the build status
[07:28:57.383] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:57.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:82:0) after 1m0s
[07:29:57.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:82:0)
[07:29:57.395] Timer tick, asking Buildbucket for the build status
[07:29:57.703] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:57.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:83:0) after 1m0s
[07:30:57.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:83:0)
[07:30:57.770] Timer tick, asking Buildbucket for the build status
[07:30:57.903] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:57.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:84:0) after 1m0s
[07:31:57.951] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:84:0)
[07:31:57.951] Timer tick, asking Buildbucket for the build status
[07:31:58.314] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:58.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:85:0) after 1m0s
[07:32:58.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:85:0)
[07:32:58.372] Timer tick, asking Buildbucket for the build status
[07:32:58.549] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:58.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:86:0) after 1m0s
[07:33:58.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:86:0)
[07:33:58.839] Timer tick, asking Buildbucket for the build status
[07:33:59.143] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:59.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:87:0) after 1m0s
[07:34:59.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:87:0)
[07:34:59.327] Timer tick, asking Buildbucket for the build status
[07:34:59.396] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:59.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:88:0) after 1m0s
[07:35:59.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:88:0)
[07:35:59.553] Timer tick, asking Buildbucket for the build status
[07:35:59.724] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:59.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:89:0) after 1m0s
[07:36:59.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:89:0)
[07:36:59.780] Timer tick, asking Buildbucket for the build status
[07:37:00.198] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:00.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:90:0) after 1m0s
[07:38:00.226] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:90:0)
[07:38:00.226] Timer tick, asking Buildbucket for the build status
[07:38:00.795] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:00.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:91:0) after 1m0s
[07:39:01.082] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:91:0)
[07:39:01.082] Timer tick, asking Buildbucket for the build status
[07:39:01.373] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:01.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:92:0) after 1m0s
[07:40:01.528] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:92:0)
[07:40:01.528] Timer tick, asking Buildbucket for the build status
[07:40:02.087] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:02.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:93:0) after 1m0s
[07:41:03.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:93:0)
[07:41:03.079] Timer tick, asking Buildbucket for the build status
[07:41:03.317] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:03.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:94:0) after 1m0s
[07:42:03.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:94:0)
[07:42:03.473] Timer tick, asking Buildbucket for the build status
[07:42:03.649] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:03.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:95:0) after 1m0s
[07:43:03.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:95:0)
[07:43:03.808] Timer tick, asking Buildbucket for the build status
[07:43:04.128] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:04.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:96:0) after 1m0s
[07:44:04.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:96:0)
[07:44:04.174] Timer tick, asking Buildbucket for the build status
[07:44:04.369] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:04.369] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:97:0) after 1m0s
[07:45:04.405] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:97:0)
[07:45:04.405] Timer tick, asking Buildbucket for the build status
[07:45:04.615] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:04.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:98:0) after 1m0s
[07:46:04.739] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:98:0)
[07:46:04.739] Timer tick, asking Buildbucket for the build status
[07:46:04.901] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:04.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:99:0) after 1m0s
[07:47:04.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:99:0)
[07:47:04.924] Timer tick, asking Buildbucket for the build status
[07:47:05.052] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:05.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:100:0) after 1m0s
[07:48:05.193] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:100:0)
[07:48:05.193] Timer tick, asking Buildbucket for the build status
[07:48:05.616] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:05.616] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:101:0) after 1m0s
[07:49:05.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:101:0)
[07:49:05.687] Timer tick, asking Buildbucket for the build status
[07:49:05.809] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:05.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:102:0) after 1m0s
[07:50:05.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:102:0)
[07:50:05.978] Timer tick, asking Buildbucket for the build status
[07:50:06.160] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:06.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:103:0) after 1m0s
[07:51:06.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:103:0)
[07:51:06.263] Timer tick, asking Buildbucket for the build status
[07:51:06.387] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:06.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:104:0) after 1m0s
[07:52:06.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:104:0)
[07:52:06.505] Timer tick, asking Buildbucket for the build status
[07:52:06.676] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:06.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:105:0) after 1m0s
[07:53:06.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:105:0)
[07:53:06.719] Timer tick, asking Buildbucket for the build status
[07:53:07.104] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:07.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:106:0) after 1m0s
[07:54:07.128] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:106:0)
[07:54:07.128] Timer tick, asking Buildbucket for the build status
[07:54:07.424] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:07.424] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:107:0) after 1m0s
[07:55:07.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:107:0)
[07:55:07.449] Timer tick, asking Buildbucket for the build status
[07:55:07.692] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:07.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:108:0) after 1m0s
[07:56:07.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:108:0)
[07:56:07.742] Timer tick, asking Buildbucket for the build status
[07:56:07.898] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:07.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:109:0) after 1m0s
[07:57:08.138] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:109:0)
[07:57:08.138] Timer tick, asking Buildbucket for the build status
[07:57:08.202] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:08.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:110:0) after 1m0s
[07:58:08.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:110:0)
[07:58:08.303] Timer tick, asking Buildbucket for the build status
[07:58:08.466] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:08.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:111:0) after 1m0s
[07:59:08.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:111:0)
[07:59:08.563] Timer tick, asking Buildbucket for the build status
[07:59:08.689] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:08.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:112:0) after 1m0s
[08:00:08.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:112:0)
[08:00:08.824] Timer tick, asking Buildbucket for the build status
[08:00:09.189] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:09.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:113:0) after 1m0s
[08:01:09.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:113:0)
[08:01:09.844] Timer tick, asking Buildbucket for the build status
[08:01:10.035] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:10.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:114:0) after 1m0s
[08:02:10.197] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:114:0)
[08:02:10.197] Timer tick, asking Buildbucket for the build status
[08:02:10.268] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:10.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:115:0) after 1m0s
[08:03:10.420] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:115:0)
[08:03:10.420] Timer tick, asking Buildbucket for the build status
[08:03:10.831] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:10.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:116:0) after 1m0s
[08:04:10.859] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:116:0)
[08:04:10.859] Timer tick, asking Buildbucket for the build status
[08:04:11.015] Build 8874459872752414240: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:11.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039889940250187552:117:0) after 1m0s
[08:04:34.436] Received PubSub notification, asking Buildbucket for the build status
[08:04:34.598] Build 8874459872752414240: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:04:34.598] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1595059473519156",
"created_by": "project:chromium",
"created_ts": "1595052569586392",
"id": "8874459872752414240",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5211af9ac644bae721157491efb39f7fbb7e1d7f\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$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-cju1\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 126, \"got_angle_revision\": \"5a4af4624777b0cd91d299eeeb13222414607f1b\", \"got_dawn_revision\": \"1233b66c90d45c3c2b16a6d0635183201893dffc\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"5211af9ac644bae721157491efb39f7fbb7e1d7f\", \"got_revision_cp\": \"refs/heads/master@{#789751}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"a8b8ef775c7539254541c5a1070ce2948ae7be5c\", \"got_v8_revision\": \"1d62b368a26884aed96eb207ecb2fc499255d474\", \"got_v8_revision_cp\": \"refs/heads/8.6.180@{#1}\", \"got_webrtc_revision\": \"d1e8e6122c754cf178cf918d341d04ca1bb196ef\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31763}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"5211af9ac644bae721157491efb39f7fbb7e1d7f\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-9-cju1\"], \"image\": [\"chrome-win10-1703-20070700-8a9e7990cc1\"], \"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\": [\"5409-9d77717\"], \"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": "1595052574202464",
"status": "COMPLETED",
"status_changed_ts": "1595059474042513",
"tags": [
"build_address:luci.chromium.ci/win-official/126",
"builder:win-official",
"buildset:commit/git/5211af9ac644bae721157491efb39f7fbb7e1d7f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5211af9ac644bae721157491efb39f7fbb7e1d7f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039889940250187552",
"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/8874459872752414240/+/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:4d795c3f0a89e810",
"user_agent:luci-scheduler"
],
"updated_ts": "1595059474048877",
"url": "https://ci.chromium.org/b/8874459872752414240",
"utcnow_ts": "1595059474578098"
}
}
[08:04:34.598] Invocation finished in 1h55m11.546134939s with status SUCCEEDED