[15:16:26.303] New invocation is queued and will start shortly
[15:16:27.385] Starting the invocation (attempt 1)
[15:16:27.431] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:16:27.431] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:16:27.431] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021917323295530288",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"cce041fec157dac7131460203fba81ea4f11e10b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9021917323295530288",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/cce041fec157dac7131460203fba81ea4f11e10b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cce041fec157dac7131460203fba81ea4f11e10b",
"gitiles_ref:refs/heads/master"
]
}
[15:16:28.265] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612192587517701",
"id": "8856487261309963296",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cce041fec157dac7131460203fba81ea4f11e10b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612192587990393",
"tags": [
"build_address:luci.chromium.ci/win-official/1512",
"builder:win-official",
"buildset:commit/git/cce041fec157dac7131460203fba81ea4f11e10b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cce041fec157dac7131460203fba81ea4f11e10b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021917323295530288",
"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/8856487261309963296/+/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": "1612192587990503",
"url": "https://ci.chromium.org/b/8856487261309963296",
"utcnow_ts": "1612192588234909"
}
}
[15:16:28.265] Task URL: https://ci.chromium.org/b/8856487261309963296
[15:16:28.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:2:0) after 1m0s
[15:17:17.707] Received PubSub notification, asking Buildbucket for the build status
[15:17:17.778] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:28.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:2:0)
[15:17:28.510] Timer tick, asking Buildbucket for the build status
[15:17:28.589] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:28.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:4:0) after 1m0s
[15:18:28.793] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:4:0)
[15:18:28.793] Timer tick, asking Buildbucket for the build status
[15:18:28.867] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:28.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:5:0) after 1m0s
[15:19:28.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:5:0)
[15:19:28.928] Timer tick, asking Buildbucket for the build status
[15:19:28.993] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:28.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:6:0) after 1m0s
[15:20:29.147] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:6:0)
[15:20:29.148] Timer tick, asking Buildbucket for the build status
[15:20:29.219] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:29.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:7:0) after 1m0s
[15:21:29.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:7:0)
[15:21:29.369] Timer tick, asking Buildbucket for the build status
[15:21:29.645] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:29.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:8:0) after 1m0s
[15:22:29.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:8:0)
[15:22:29.723] Timer tick, asking Buildbucket for the build status
[15:22:29.804] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:29.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:9:0) after 1m0s
[15:23:29.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:9:0)
[15:23:29.924] Timer tick, asking Buildbucket for the build status
[15:23:30.191] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:30.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:10:0) after 1m0s
[15:24:30.278] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:10:0)
[15:24:30.278] Timer tick, asking Buildbucket for the build status
[15:24:30.346] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:30.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:11:0) after 1m0s
[15:25:30.464] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:11:0)
[15:25:30.464] Timer tick, asking Buildbucket for the build status
[15:25:30.556] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:30.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:12:0) after 1m0s
[15:26:30.649] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:12:0)
[15:26:30.649] Timer tick, asking Buildbucket for the build status
[15:26:30.984] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:30.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:13:0) after 1m0s
[15:27:31.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:13:0)
[15:27:31.038] Timer tick, asking Buildbucket for the build status
[15:27:31.129] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:31.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:14:0) after 1m0s
[15:28:31.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:14:0)
[15:28:31.182] Timer tick, asking Buildbucket for the build status
[15:28:31.253] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:31.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:15:0) after 1m0s
[15:29:31.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:15:0)
[15:29:31.316] Timer tick, asking Buildbucket for the build status
[15:29:31.411] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:31.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:16:0) after 1m0s
[15:30:31.436] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:16:0)
[15:30:31.436] Timer tick, asking Buildbucket for the build status
[15:30:31.525] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:31.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:17:0) after 1m0s
[15:31:31.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:17:0)
[15:31:31.535] Timer tick, asking Buildbucket for the build status
[15:31:31.898] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:31.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:18:0) after 1m0s
[15:32:31.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:18:0)
[15:32:31.988] Timer tick, asking Buildbucket for the build status
[15:32:32.138] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:32.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:19:0) after 1m0s
[15:33:32.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:19:0)
[15:33:32.349] Timer tick, asking Buildbucket for the build status
[15:33:32.513] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:32.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:20:0) after 1m0s
[15:34:32.638] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:20:0)
[15:34:32.638] Timer tick, asking Buildbucket for the build status
[15:34:32.809] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:32.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:21:0) after 1m0s
[15:35:32.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:21:0)
[15:35:32.901] Timer tick, asking Buildbucket for the build status
[15:35:33.036] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:33.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:22:0) after 1m0s
[15:36:33.221] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:22:0)
[15:36:33.221] Timer tick, asking Buildbucket for the build status
[15:36:33.354] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:33.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:23:0) after 1m0s
[15:37:33.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:23:0)
[15:37:33.425] Timer tick, asking Buildbucket for the build status
[15:37:33.594] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:33.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:24:0) after 1m0s
[15:38:33.685] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:24:0)
[15:38:33.685] Timer tick, asking Buildbucket for the build status
[15:38:33.809] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:33.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:25:0) after 1m0s
[15:39:33.883] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:25:0)
[15:39:33.883] Timer tick, asking Buildbucket for the build status
[15:39:33.945] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:33.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:26:0) after 1m0s
[15:40:34.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:26:0)
[15:40:34.101] Timer tick, asking Buildbucket for the build status
[15:40:34.260] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:34.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:27:0) after 1m0s
[15:41:34.370] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:27:0)
[15:41:34.370] Timer tick, asking Buildbucket for the build status
[15:41:34.487] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:34.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:28:0) after 1m0s
[15:42:34.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:28:0)
[15:42:34.505] Timer tick, asking Buildbucket for the build status
[15:42:34.893] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:34.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:29:0) after 1m0s
[15:43:34.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:29:0)
[15:43:34.908] Timer tick, asking Buildbucket for the build status
[15:43:35.009] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:35.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:30:0) after 1m0s
[15:44:35.063] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:30:0)
[15:44:35.063] Timer tick, asking Buildbucket for the build status
[15:44:35.154] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:35.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:31:0) after 1m0s
[15:45:35.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:31:0)
[15:45:35.281] Timer tick, asking Buildbucket for the build status
[15:45:35.416] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:35.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:32:0) after 1m0s
[15:46:35.513] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:32:0)
[15:46:35.513] Timer tick, asking Buildbucket for the build status
[15:46:35.748] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:35.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:33:0) after 1m0s
[15:47:35.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:33:0)
[15:47:35.768] Timer tick, asking Buildbucket for the build status
[15:47:35.818] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:35.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:34:0) after 1m0s
[15:48:35.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:34:0)
[15:48:35.896] Timer tick, asking Buildbucket for the build status
[15:48:36.016] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:36.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:35:0) after 1m0s
[15:49:36.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:35:0)
[15:49:36.158] Timer tick, asking Buildbucket for the build status
[15:49:36.216] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:36.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:36:0) after 1m0s
[15:50:36.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:36:0)
[15:50:36.813] Timer tick, asking Buildbucket for the build status
[15:50:36.887] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:36.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:37:0) after 1m0s
[15:51:37.026] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:37:0)
[15:51:37.026] Timer tick, asking Buildbucket for the build status
[15:51:37.127] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:37.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:38:0) after 1m0s
[15:52:37.162] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:38:0)
[15:52:37.162] Timer tick, asking Buildbucket for the build status
[15:52:37.239] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:37.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:39:0) after 1m0s
[15:53:37.220] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:39:0)
[15:53:37.220] Timer tick, asking Buildbucket for the build status
[15:53:37.401] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:37.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:40:0) after 1m0s
[15:54:37.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:40:0)
[15:54:37.441] Timer tick, asking Buildbucket for the build status
[15:54:37.578] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:37.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:41:0) after 1m0s
[15:55:37.703] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:41:0)
[15:55:37.703] Timer tick, asking Buildbucket for the build status
[15:55:38.058] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:38.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:42:0) after 1m0s
[15:56:38.174] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:42:0)
[15:56:38.174] Timer tick, asking Buildbucket for the build status
[15:56:38.287] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:38.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:43:0) after 1m0s
[15:57:38.350] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:43:0)
[15:57:38.350] Timer tick, asking Buildbucket for the build status
[15:57:38.410] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:38.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:44:0) after 1m0s
[15:58:38.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:44:0)
[15:58:38.491] Timer tick, asking Buildbucket for the build status
[15:58:38.566] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:38.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:45:0) after 1m0s
[15:59:38.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:45:0)
[15:59:38.664] Timer tick, asking Buildbucket for the build status
[15:59:38.734] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:38.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:46:0) after 1m0s
[16:00:38.753] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:46:0)
[16:00:38.753] Timer tick, asking Buildbucket for the build status
[16:00:38.813] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:38.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:47:0) after 1m0s
[16:01:38.828] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:47:0)
[16:01:38.828] Timer tick, asking Buildbucket for the build status
[16:01:39.072] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:39.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:48:0) after 1m0s
[16:02:39.245] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:48:0)
[16:02:39.245] Timer tick, asking Buildbucket for the build status
[16:02:39.312] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:39.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:49:0) after 1m0s
[16:03:39.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:49:0)
[16:03:39.362] Timer tick, asking Buildbucket for the build status
[16:03:39.448] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:39.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:50:0) after 1m0s
[16:04:39.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:50:0)
[16:04:39.635] Timer tick, asking Buildbucket for the build status
[16:04:39.709] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:39.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:51:0) after 1m0s
[16:05:39.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:51:0)
[16:05:39.830] Timer tick, asking Buildbucket for the build status
[16:05:39.888] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:39.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:52:0) after 1m0s
[16:06:40.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:52:0)
[16:06:40.008] Timer tick, asking Buildbucket for the build status
[16:06:40.225] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:40.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:53:0) after 1m0s
[16:07:40.282] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:53:0)
[16:07:40.282] Timer tick, asking Buildbucket for the build status
[16:07:40.364] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:40.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:54:0) after 1m0s
[16:08:40.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:54:0)
[16:08:40.395] Timer tick, asking Buildbucket for the build status
[16:08:40.625] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:40.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:55:0) after 1m0s
[16:09:40.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:55:0)
[16:09:40.643] Timer tick, asking Buildbucket for the build status
[16:09:40.704] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:40.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:56:0) after 1m0s
[16:10:40.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:56:0)
[16:10:40.818] Timer tick, asking Buildbucket for the build status
[16:10:40.887] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:40.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:57:0) after 1m0s
[16:11:40.992] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:57:0)
[16:11:40.992] Timer tick, asking Buildbucket for the build status
[16:11:41.157] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:41.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:58:0) after 1m0s
[16:12:41.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:58:0)
[16:12:41.261] Timer tick, asking Buildbucket for the build status
[16:12:41.323] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:41.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:59:0) after 1m0s
[16:13:41.461] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:59:0)
[16:13:41.461] Timer tick, asking Buildbucket for the build status
[16:13:41.525] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:41.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:60:0) after 1m0s
[16:14:41.690] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:60:0)
[16:14:41.690] Timer tick, asking Buildbucket for the build status
[16:14:41.769] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:41.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:61:0) after 1m0s
[16:15:41.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:61:0)
[16:15:41.879] Timer tick, asking Buildbucket for the build status
[16:15:41.938] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:41.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:62:0) after 1m0s
[16:16:42.020] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:62:0)
[16:16:42.020] Timer tick, asking Buildbucket for the build status
[16:16:42.199] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:42.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:63:0) after 1m0s
[16:17:42.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:63:0)
[16:17:42.316] Timer tick, asking Buildbucket for the build status
[16:17:42.384] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:42.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:64:0) after 1m0s
[16:18:42.424] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:64:0)
[16:18:42.424] Timer tick, asking Buildbucket for the build status
[16:18:42.594] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:42.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:65:0) after 1m0s
[16:19:42.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:65:0)
[16:19:42.654] Timer tick, asking Buildbucket for the build status
[16:19:42.718] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:42.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:66:0) after 1m0s
[16:20:42.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:66:0)
[16:20:42.745] Timer tick, asking Buildbucket for the build status
[16:20:42.823] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:42.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:67:0) after 1m0s
[16:21:42.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:67:0)
[16:21:42.825] Timer tick, asking Buildbucket for the build status
[16:21:42.914] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:42.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:68:0) after 1m0s
[16:22:42.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:68:0)
[16:22:42.974] Timer tick, asking Buildbucket for the build status
[16:22:43.261] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:43.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:69:0) after 1m0s
[16:23:43.357] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:69:0)
[16:23:43.357] Timer tick, asking Buildbucket for the build status
[16:23:43.534] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:43.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:70:0) after 1m0s
[16:24:43.624] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:70:0)
[16:24:43.624] Timer tick, asking Buildbucket for the build status
[16:24:43.945] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:43.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:71:0) after 1m0s
[16:25:44.002] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:71:0)
[16:25:44.002] Timer tick, asking Buildbucket for the build status
[16:25:44.159] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:44.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:72:0) after 1m0s
[16:26:44.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:72:0)
[16:26:44.228] Timer tick, asking Buildbucket for the build status
[16:26:44.349] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:44.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:73:0) after 1m0s
[16:27:44.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:73:0)
[16:27:44.460] Timer tick, asking Buildbucket for the build status
[16:27:44.524] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:44.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:74:0) after 1m0s
[16:28:44.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:74:0)
[16:28:44.648] Timer tick, asking Buildbucket for the build status
[16:28:44.718] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:44.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:75:0) after 1m0s
[16:29:44.734] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:75:0)
[16:29:44.734] Timer tick, asking Buildbucket for the build status
[16:29:45.058] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:45.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:76:0) after 1m0s
[16:30:45.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:76:0)
[16:30:45.125] Timer tick, asking Buildbucket for the build status
[16:30:45.304] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:45.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:77:0) after 1m0s
[16:31:45.416] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:77:0)
[16:31:45.416] Timer tick, asking Buildbucket for the build status
[16:31:45.718] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:45.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:78:0) after 1m0s
[16:32:45.761] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:78:0)
[16:32:45.761] Timer tick, asking Buildbucket for the build status
[16:32:45.961] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:45.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:79:0) after 1m0s
[16:33:45.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:79:0)
[16:33:45.990] Timer tick, asking Buildbucket for the build status
[16:33:46.058] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:46.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:80:0) after 1m0s
[16:34:46.086] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:80:0)
[16:34:46.086] Timer tick, asking Buildbucket for the build status
[16:34:46.151] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:46.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:81:0) after 1m0s
[16:35:46.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:81:0)
[16:35:46.242] Timer tick, asking Buildbucket for the build status
[16:35:46.304] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:46.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:82:0) after 1m0s
[16:36:46.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:82:0)
[16:36:46.376] Timer tick, asking Buildbucket for the build status
[16:36:46.487] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:46.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:83:0) after 1m0s
[16:37:46.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:83:0)
[16:37:46.539] Timer tick, asking Buildbucket for the build status
[16:37:46.640] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:46.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:84:0) after 1m0s
[16:38:46.701] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:84:0)
[16:38:46.701] Timer tick, asking Buildbucket for the build status
[16:38:46.781] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:46.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:85:0) after 1m0s
[16:39:46.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:85:0)
[16:39:46.867] Timer tick, asking Buildbucket for the build status
[16:39:46.954] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:46.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:86:0) after 1m0s
[16:40:47.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:86:0)
[16:40:47.088] Timer tick, asking Buildbucket for the build status
[16:40:47.176] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:47.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:87:0) after 1m0s
[16:41:47.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:87:0)
[16:41:47.206] Timer tick, asking Buildbucket for the build status
[16:41:47.399] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:47.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:88:0) after 1m0s
[16:42:47.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:88:0)
[16:42:47.453] Timer tick, asking Buildbucket for the build status
[16:42:47.520] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:47.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:89:0) after 1m0s
[16:43:47.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:89:0)
[16:43:47.565] Timer tick, asking Buildbucket for the build status
[16:43:47.620] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:47.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:90:0) after 1m0s
[16:44:47.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:90:0)
[16:44:47.636] Timer tick, asking Buildbucket for the build status
[16:44:47.707] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:47.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:91:0) after 1m0s
[16:45:47.895] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:91:0)
[16:45:47.895] Timer tick, asking Buildbucket for the build status
[16:45:48.356] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:48.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:92:0) after 1m0s
[16:46:48.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:92:0)
[16:46:48.431] Timer tick, asking Buildbucket for the build status
[16:46:48.706] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:48.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:93:0) after 1m0s
[16:47:48.888] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:93:0)
[16:47:48.888] Timer tick, asking Buildbucket for the build status
[16:47:49.094] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:49.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:94:0) after 1m0s
[16:48:49.130] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:94:0)
[16:48:49.130] Timer tick, asking Buildbucket for the build status
[16:48:49.227] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:49.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:95:0) after 1m0s
[16:49:49.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:95:0)
[16:49:49.491] Timer tick, asking Buildbucket for the build status
[16:49:49.582] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:49.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:96:0) after 1m0s
[16:50:49.713] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:96:0)
[16:50:49.713] Timer tick, asking Buildbucket for the build status
[16:50:49.924] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:49.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:97:0) after 1m0s
[16:51:49.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:97:0)
[16:51:49.969] Timer tick, asking Buildbucket for the build status
[16:51:50.315] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:50.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:98:0) after 1m0s
[16:52:50.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:98:0)
[16:52:50.428] Timer tick, asking Buildbucket for the build status
[16:52:50.495] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:50.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:99:0) after 1m0s
[16:53:50.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:99:0)
[16:53:50.614] Timer tick, asking Buildbucket for the build status
[16:53:50.707] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:50.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:100:0) after 1m0s
[16:54:50.785] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:100:0)
[16:54:50.785] Timer tick, asking Buildbucket for the build status
[16:54:51.015] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:51.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:101:0) after 1m0s
[16:55:51.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:101:0)
[16:55:51.096] Timer tick, asking Buildbucket for the build status
[16:55:51.207] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:51.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:102:0) after 1m0s
[16:56:51.252] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:102:0)
[16:56:51.252] Timer tick, asking Buildbucket for the build status
[16:56:51.733] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:51.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:103:0) after 1m0s
[16:57:51.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:103:0)
[16:57:51.759] Timer tick, asking Buildbucket for the build status
[16:57:51.851] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:51.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:104:0) after 1m0s
[16:58:51.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:104:0)
[16:58:51.861] Timer tick, asking Buildbucket for the build status
[16:58:52.046] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:52.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:105:0) after 1m0s
[16:59:52.091] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:105:0)
[16:59:52.091] Timer tick, asking Buildbucket for the build status
[16:59:52.259] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:52.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:106:0) after 1m0s
[17:00:52.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:106:0)
[17:00:52.277] Timer tick, asking Buildbucket for the build status
[17:00:52.344] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:52.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:107:0) after 1m0s
[17:01:52.442] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:107:0)
[17:01:52.442] Timer tick, asking Buildbucket for the build status
[17:01:52.769] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:52.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:108:0) after 1m0s
[17:02:52.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:108:0)
[17:02:52.823] Timer tick, asking Buildbucket for the build status
[17:02:52.914] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:52.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:109:0) after 1m0s
[17:03:52.998] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:109:0)
[17:03:52.998] Timer tick, asking Buildbucket for the build status
[17:03:53.269] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:53.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:110:0) after 1m0s
[17:04:53.347] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:110:0)
[17:04:53.347] Timer tick, asking Buildbucket for the build status
[17:04:53.721] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:53.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:111:0) after 1m0s
[17:05:53.787] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:111:0)
[17:05:53.787] Timer tick, asking Buildbucket for the build status
[17:05:54.082] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:54.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:112:0) after 1m0s
[17:06:54.261] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:112:0)
[17:06:54.261] Timer tick, asking Buildbucket for the build status
[17:06:54.372] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:54.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:113:0) after 1m0s
[17:07:54.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:113:0)
[17:07:54.441] Timer tick, asking Buildbucket for the build status
[17:07:54.850] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:54.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:114:0) after 1m0s
[17:08:54.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:114:0)
[17:08:54.963] Timer tick, asking Buildbucket for the build status
[17:08:55.263] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:55.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:115:0) after 1m0s
[17:09:55.355] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:115:0)
[17:09:55.355] Timer tick, asking Buildbucket for the build status
[17:09:55.509] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:55.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:116:0) after 1m0s
[17:10:55.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:116:0)
[17:10:55.664] Timer tick, asking Buildbucket for the build status
[17:10:55.764] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:55.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:117:0) after 1m0s
[17:11:55.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:117:0)
[17:11:55.821] Timer tick, asking Buildbucket for the build status
[17:11:56.031] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:56.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:118:0) after 1m0s
[17:12:56.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:118:0)
[17:12:56.189] Timer tick, asking Buildbucket for the build status
[17:12:56.262] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:56.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:119:0) after 1m0s
[17:13:56.315] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:119:0)
[17:13:56.315] Timer tick, asking Buildbucket for the build status
[17:13:56.632] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:56.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:120:0) after 1m0s
[17:14:56.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:120:0)
[17:14:56.616] Timer tick, asking Buildbucket for the build status
[17:14:56.736] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:56.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:121:0) after 1m0s
[17:15:56.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:121:0)
[17:15:56.813] Timer tick, asking Buildbucket for the build status
[17:15:56.874] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:56.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:122:0) after 1m0s
[17:16:57.024] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:122:0)
[17:16:57.024] Timer tick, asking Buildbucket for the build status
[17:16:57.175] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:57.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:123:0) after 1m0s
[17:17:57.392] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:123:0)
[17:17:57.392] Timer tick, asking Buildbucket for the build status
[17:17:57.519] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:57.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:124:0) after 1m0s
[17:18:57.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:124:0)
[17:18:57.657] Timer tick, asking Buildbucket for the build status
[17:18:57.744] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:57.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:125:0) after 1m0s
[17:19:57.824] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:125:0)
[17:19:57.824] Timer tick, asking Buildbucket for the build status
[17:19:57.949] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:57.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:126:0) after 1m0s
[17:20:58.134] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:126:0)
[17:20:58.134] Timer tick, asking Buildbucket for the build status
[17:20:58.204] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:58.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:127:0) after 1m0s
[17:21:58.487] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:127:0)
[17:21:58.487] Timer tick, asking Buildbucket for the build status
[17:21:58.615] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:58.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:128:0) after 1m0s
[17:22:58.914] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:128:0)
[17:22:58.914] Timer tick, asking Buildbucket for the build status
[17:22:58.984] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:58.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:129:0) after 1m0s
[17:23:59.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:129:0)
[17:23:59.059] Timer tick, asking Buildbucket for the build status
[17:23:59.133] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:59.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:130:0) after 1m0s
[17:24:59.358] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:130:0)
[17:24:59.358] Timer tick, asking Buildbucket for the build status
[17:24:59.466] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:59.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:131:0) after 1m0s
[17:25:59.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:131:0)
[17:25:59.768] Timer tick, asking Buildbucket for the build status
[17:25:59.847] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:59.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:132:0) after 1m0s
[17:26:59.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:132:0)
[17:26:59.985] Timer tick, asking Buildbucket for the build status
[17:27:00.323] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:00.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:133:0) after 1m0s
[17:28:00.475] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:133:0)
[17:28:00.475] Timer tick, asking Buildbucket for the build status
[17:28:00.706] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:00.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:134:0) after 1m0s
[17:29:01.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:134:0)
[17:29:01.204] Timer tick, asking Buildbucket for the build status
[17:29:01.371] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:01.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:135:0) after 1m0s
[17:30:01.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:135:0)
[17:30:01.760] Timer tick, asking Buildbucket for the build status
[17:30:01.868] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:01.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:136:0) after 1m0s
[17:31:02.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:136:0)
[17:31:02.142] Timer tick, asking Buildbucket for the build status
[17:31:02.307] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:02.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:137:0) after 1m0s
[17:32:02.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:137:0)
[17:32:02.537] Timer tick, asking Buildbucket for the build status
[17:32:03.347] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:03.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:138:0) after 1m0s
[17:33:03.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:138:0)
[17:33:03.645] Timer tick, asking Buildbucket for the build status
[17:33:03.751] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:03.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:139:0) after 1m0s
[17:34:03.879] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:139:0)
[17:34:03.879] Timer tick, asking Buildbucket for the build status
[17:34:03.964] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:03.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:140:0) after 1m0s
[17:35:04.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:140:0)
[17:35:04.155] Timer tick, asking Buildbucket for the build status
[17:35:04.339] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:04.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:141:0) after 1m0s
[17:36:04.448] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:141:0)
[17:36:04.448] Timer tick, asking Buildbucket for the build status
[17:36:04.568] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:04.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:142:0) after 1m0s
[17:37:04.664] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:142:0)
[17:37:04.664] Timer tick, asking Buildbucket for the build status
[17:37:05.022] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:05.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:143:0) after 1m0s
[17:38:05.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:143:0)
[17:38:05.072] Timer tick, asking Buildbucket for the build status
[17:38:05.486] Build 8856487261309963296: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:05.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9021917323295530288:144:0) after 1m0s
[17:38:54.408] Received PubSub notification, asking Buildbucket for the build status
[17:38:54.497] Build 8856487261309963296: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:38:54.497] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612201133521902",
"created_by": "project:chromium",
"created_ts": "1612192587517701",
"id": "8856487261309963296",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cce041fec157dac7131460203fba81ea4f11e10b\"}}",
"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\": \"cce041fec157dac7131460203fba81ea4f11e10b\", \"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-2-ksg6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1512, \"got_angle_revision\": \"85a9ec10b18fdb9cabb8177d95892e46d7abcc87\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"cce041fec157dac7131460203fba81ea4f11e10b\", \"got_revision_cp\": \"refs/heads/master@{#849111}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8b4cf1c2491d56cf9eda153b5455e4b295732b7c\", \"got_v8_revision\": \"6c4d607a0aaf63ff4b68202d6d7bced976023ae7\", \"got_v8_revision_cp\": \"refs/heads/9.0.103@{#1}\", \"got_webrtc_revision\": \"1f1e190acc8012657965ec3e2c51c7ac72470af7\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33118}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"cce041fec157dac7131460203fba81ea4f11e10b\"}, \"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-2-ksg6\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612192636338766",
"status": "COMPLETED",
"status_changed_ts": "1612201133893275",
"tags": [
"build_address:luci.chromium.ci/win-official/1512",
"builder:win-official",
"buildset:commit/git/cce041fec157dac7131460203fba81ea4f11e10b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/cce041fec157dac7131460203fba81ea4f11e10b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021917323295530288",
"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/8856487261309963296/+/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:5176fc29d65a7a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1612201133898078",
"url": "https://ci.chromium.org/b/8856487261309963296",
"utcnow_ts": "1612201134486543"
}
}
[17:38:54.497] Invocation finished in 2h22m28.256865268s with status SUCCEEDED