[22:22:18.070] New invocation is queued and will start shortly
[22:22:19.211] Starting the invocation (attempt 1)
[22:22:19.251] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:22:19.251] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:22:19.251] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9039194550862363856",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"811b6a4e37932e75ea8b59da96ffc11dd409ba8f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9039194550862363856",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/811b6a4e37932e75ea8b59da96ffc11dd409ba8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/811b6a4e37932e75ea8b59da96ffc11dd409ba8f",
"gitiles_ref:refs/heads/master"
]
}
[22:22:20.623] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1595715739362575",
"id": "8873764488840568896",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"811b6a4e37932e75ea8b59da96ffc11dd409ba8f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1595715740297931",
"tags": [
"build_address:luci.chromium.ci/win-official/174",
"builder:win-official",
"buildset:commit/git/811b6a4e37932e75ea8b59da96ffc11dd409ba8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/811b6a4e37932e75ea8b59da96ffc11dd409ba8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039194550862363856",
"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/8873764488840568896/+/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": "1595715740298092",
"url": "https://ci.chromium.org/b/8873764488840568896",
"utcnow_ts": "1595715740612156"
}
}
[22:22:20.623] Task URL: https://ci.chromium.org/b/8873764488840568896
[22:22:20.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:2:0) after 1m0s
[22:22:22.441] Received PubSub notification, asking Buildbucket for the build status
[22:22:22.522] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:20.656] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:2:0)
[22:23:20.656] Timer tick, asking Buildbucket for the build status
[22:23:20.779] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:20.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:4:0) after 1m0s
[22:24:20.807] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:4:0)
[22:24:20.807] Timer tick, asking Buildbucket for the build status
[22:24:20.976] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:20.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:5:0) after 1m0s
[22:25:20.970] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:5:0)
[22:25:20.970] Timer tick, asking Buildbucket for the build status
[22:25:21.177] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:21.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:6:0) after 1m0s
[22:26:21.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:6:0)
[22:26:21.233] Timer tick, asking Buildbucket for the build status
[22:26:21.320] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:21.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:7:0) after 1m0s
[22:27:21.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:7:0)
[22:27:21.366] Timer tick, asking Buildbucket for the build status
[22:27:22.227] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:22.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:8:0) after 1m0s
[22:28:22.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:8:0)
[22:28:22.397] Timer tick, asking Buildbucket for the build status
[22:28:22.553] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:22.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:9:0) after 1m0s
[22:29:22.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:9:0)
[22:29:22.588] Timer tick, asking Buildbucket for the build status
[22:29:22.972] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:22.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:10:0) after 1m0s
[22:30:23.005] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:10:0)
[22:30:23.005] Timer tick, asking Buildbucket for the build status
[22:30:23.177] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:23.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:11:0) after 1m0s
[22:31:23.169] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:11:0)
[22:31:23.169] Timer tick, asking Buildbucket for the build status
[22:31:23.591] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:23.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:12:0) after 1m0s
[22:32:23.698] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:12:0)
[22:32:23.698] Timer tick, asking Buildbucket for the build status
[22:32:24.581] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:24.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:13:0) after 1m0s
[22:33:24.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:13:0)
[22:33:24.604] Timer tick, asking Buildbucket for the build status
[22:33:24.677] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:24.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:14:0) after 1m0s
[22:34:24.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:14:0)
[22:34:24.723] Timer tick, asking Buildbucket for the build status
[22:34:24.793] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:24.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:15:0) after 1m0s
[22:35:24.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:15:0)
[22:35:24.817] Timer tick, asking Buildbucket for the build status
[22:35:24.914] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:24.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:16:0) after 1m0s
[22:36:24.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:16:0)
[22:36:24.947] Timer tick, asking Buildbucket for the build status
[22:36:25.185] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:25.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:17:0) after 1m0s
[22:37:25.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:17:0)
[22:37:25.206] Timer tick, asking Buildbucket for the build status
[22:37:25.411] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:25.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:18:0) after 1m0s
[22:38:25.432] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:18:0)
[22:38:25.432] Timer tick, asking Buildbucket for the build status
[22:38:25.932] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:25.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:19:0) after 1m0s
[22:39:25.948] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:19:0)
[22:39:25.948] Timer tick, asking Buildbucket for the build status
[22:39:26.178] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:26.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:20:0) after 1m0s
[22:40:26.228] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:20:0)
[22:40:26.228] Timer tick, asking Buildbucket for the build status
[22:40:26.382] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:26.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:21:0) after 1m0s
[22:41:26.407] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:21:0)
[22:41:26.407] Timer tick, asking Buildbucket for the build status
[22:41:26.512] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:26.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:22:0) after 1m0s
[22:42:26.599] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:22:0)
[22:42:26.599] Timer tick, asking Buildbucket for the build status
[22:42:26.709] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:26.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:23:0) after 1m0s
[22:43:26.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:23:0)
[22:43:26.735] Timer tick, asking Buildbucket for the build status
[22:43:26.851] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:26.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:24:0) after 1m0s
[22:44:26.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:24:0)
[22:44:26.863] Timer tick, asking Buildbucket for the build status
[22:44:27.013] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:27.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:25:0) after 1m0s
[22:45:27.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:25:0)
[22:45:27.076] Timer tick, asking Buildbucket for the build status
[22:45:27.404] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:27.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:26:0) after 1m0s
[22:46:27.486] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:26:0)
[22:46:27.486] Timer tick, asking Buildbucket for the build status
[22:46:27.553] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:27.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:27:0) after 1m0s
[22:47:27.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:27:0)
[22:47:27.601] Timer tick, asking Buildbucket for the build status
[22:47:27.845] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:27.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:28:0) after 1m0s
[22:48:27.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:28:0)
[22:48:27.889] Timer tick, asking Buildbucket for the build status
[22:48:27.964] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:27.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:29:0) after 1m0s
[22:49:28.109] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:29:0)
[22:49:28.109] Timer tick, asking Buildbucket for the build status
[22:49:28.360] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:28.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:30:0) after 1m0s
[22:50:28.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:30:0)
[22:50:28.410] Timer tick, asking Buildbucket for the build status
[22:50:28.501] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:28.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:31:0) after 1m0s
[22:51:28.752] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:31:0)
[22:51:28.752] Timer tick, asking Buildbucket for the build status
[22:51:28.916] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:28.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:32:0) after 1m0s
[22:52:29.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:32:0)
[22:52:29.019] Timer tick, asking Buildbucket for the build status
[22:52:29.147] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:29.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:33:0) after 1m0s
[22:53:29.281] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:33:0)
[22:53:29.281] Timer tick, asking Buildbucket for the build status
[22:53:29.343] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:29.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:34:0) after 1m0s
[22:54:29.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:34:0)
[22:54:29.431] Timer tick, asking Buildbucket for the build status
[22:54:29.514] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:29.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:35:0) after 1m0s
[22:55:29.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:35:0)
[22:55:29.666] Timer tick, asking Buildbucket for the build status
[22:55:29.770] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:29.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:36:0) after 1m0s
[22:56:29.809] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:36:0)
[22:56:29.809] Timer tick, asking Buildbucket for the build status
[22:56:29.896] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:29.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:37:0) after 1m0s
[22:57:30.017] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:37:0)
[22:57:30.017] Timer tick, asking Buildbucket for the build status
[22:57:30.178] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:30.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:38:0) after 1m0s
[22:58:30.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:38:0)
[22:58:30.384] Timer tick, asking Buildbucket for the build status
[22:58:30.445] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:30.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:39:0) after 1m0s
[22:59:30.691] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:39:0)
[22:59:30.691] Timer tick, asking Buildbucket for the build status
[22:59:30.769] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:30.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:40:0) after 1m0s
[23:00:30.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:40:0)
[23:00:30.805] Timer tick, asking Buildbucket for the build status
[23:00:30.919] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:30.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:41:0) after 1m0s
[23:01:31.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:41:0)
[23:01:31.303] Timer tick, asking Buildbucket for the build status
[23:01:31.386] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:31.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:42:0) after 1m0s
[23:02:31.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:42:0)
[23:02:31.519] Timer tick, asking Buildbucket for the build status
[23:02:31.866] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:31.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:43:0) after 1m0s
[23:03:31.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:43:0)
[23:03:31.896] Timer tick, asking Buildbucket for the build status
[23:03:32.111] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:32.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:44:0) after 1m0s
[23:04:32.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:44:0)
[23:04:32.204] Timer tick, asking Buildbucket for the build status
[23:04:32.267] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:32.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:45:0) after 1m0s
[23:05:32.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:45:0)
[23:05:32.418] Timer tick, asking Buildbucket for the build status
[23:05:32.553] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:32.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:46:0) after 1m0s
[23:06:32.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:46:0)
[23:06:32.697] Timer tick, asking Buildbucket for the build status
[23:06:32.758] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:32.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:47:0) after 1m0s
[23:07:32.924] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:47:0)
[23:07:32.924] Timer tick, asking Buildbucket for the build status
[23:07:33.053] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:33.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:48:0) after 1m0s
[23:08:33.170] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:48:0)
[23:08:33.170] Timer tick, asking Buildbucket for the build status
[23:08:33.509] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:33.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:49:0) after 1m0s
[23:09:33.555] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:49:0)
[23:09:33.555] Timer tick, asking Buildbucket for the build status
[23:09:33.696] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:33.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:50:0) after 1m0s
[23:10:33.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:50:0)
[23:10:33.775] Timer tick, asking Buildbucket for the build status
[23:10:34.183] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:34.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:51:0) after 1m0s
[23:11:34.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:51:0)
[23:11:34.255] Timer tick, asking Buildbucket for the build status
[23:11:34.341] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:34.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:52:0) after 1m0s
[23:12:34.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:52:0)
[23:12:34.669] Timer tick, asking Buildbucket for the build status
[23:12:35.076] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:35.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:53:0) after 1m0s
[23:13:35.307] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:53:0)
[23:13:35.307] Timer tick, asking Buildbucket for the build status
[23:13:35.377] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:35.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:54:0) after 1m0s
[23:14:35.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:54:0)
[23:14:35.395] Timer tick, asking Buildbucket for the build status
[23:14:35.498] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:35.498] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:55:0) after 1m0s
[23:15:35.576] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:55:0)
[23:15:35.576] Timer tick, asking Buildbucket for the build status
[23:15:35.724] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:35.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:56:0) after 1m0s
[23:16:35.747] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:56:0)
[23:16:35.747] Timer tick, asking Buildbucket for the build status
[23:16:36.009] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:36.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:57:0) after 1m0s
[23:17:36.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:57:0)
[23:17:36.035] Timer tick, asking Buildbucket for the build status
[23:17:36.413] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:36.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:58:0) after 1m0s
[23:18:36.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:58:0)
[23:18:36.514] Timer tick, asking Buildbucket for the build status
[23:18:36.597] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:36.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:59:0) after 1m0s
[23:19:36.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:59:0)
[23:19:36.635] Timer tick, asking Buildbucket for the build status
[23:19:36.689] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:36.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:60:0) after 1m0s
[23:20:36.717] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:60:0)
[23:20:36.717] Timer tick, asking Buildbucket for the build status
[23:20:36.897] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:36.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:61:0) after 1m0s
[23:21:36.953] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:61:0)
[23:21:36.953] Timer tick, asking Buildbucket for the build status
[23:21:37.841] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:37.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:62:0) after 1m0s
[23:22:37.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:62:0)
[23:22:37.917] Timer tick, asking Buildbucket for the build status
[23:22:38.257] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:38.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:63:0) after 1m0s
[23:23:38.277] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:63:0)
[23:23:38.277] Timer tick, asking Buildbucket for the build status
[23:23:38.411] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:38.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:64:0) after 1m0s
[23:24:38.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:64:0)
[23:24:38.467] Timer tick, asking Buildbucket for the build status
[23:24:38.801] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:38.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:65:0) after 1m0s
[23:25:39.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:65:0)
[23:25:39.006] Timer tick, asking Buildbucket for the build status
[23:25:39.193] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:39.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:66:0) after 1m0s
[23:26:39.220] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:66:0)
[23:26:39.220] Timer tick, asking Buildbucket for the build status
[23:26:39.316] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:39.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:67:0) after 1m0s
[23:27:39.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:67:0)
[23:27:39.362] Timer tick, asking Buildbucket for the build status
[23:27:39.570] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:39.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:68:0) after 1m0s
[23:28:39.603] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:68:0)
[23:28:39.603] Timer tick, asking Buildbucket for the build status
[23:28:39.705] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:39.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:69:0) after 1m0s
[23:29:39.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:69:0)
[23:29:39.731] Timer tick, asking Buildbucket for the build status
[23:29:39.993] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:39.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:70:0) after 1m0s
[23:30:40.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:70:0)
[23:30:40.066] Timer tick, asking Buildbucket for the build status
[23:30:40.276] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:40.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:71:0) after 1m0s
[23:31:40.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:71:0)
[23:31:40.293] Timer tick, asking Buildbucket for the build status
[23:31:41.196] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:41.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:72:0) after 1m0s
[23:32:41.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:72:0)
[23:32:41.207] Timer tick, asking Buildbucket for the build status
[23:32:41.399] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:41.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:73:0) after 1m0s
[23:33:41.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:73:0)
[23:33:41.430] Timer tick, asking Buildbucket for the build status
[23:33:41.512] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:41.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:74:0) after 1m0s
[23:34:41.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:74:0)
[23:34:41.554] Timer tick, asking Buildbucket for the build status
[23:34:41.635] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:41.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:75:0) after 1m0s
[23:35:41.697] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:75:0)
[23:35:41.697] Timer tick, asking Buildbucket for the build status
[23:35:41.788] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:41.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:76:0) after 1m0s
[23:36:41.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:76:0)
[23:36:41.811] Timer tick, asking Buildbucket for the build status
[23:36:41.915] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:41.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:77:0) after 1m0s
[23:37:41.941] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:77:0)
[23:37:41.941] Timer tick, asking Buildbucket for the build status
[23:37:42.187] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:42.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:78:0) after 1m0s
[23:38:42.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:78:0)
[23:38:42.227] Timer tick, asking Buildbucket for the build status
[23:38:42.510] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:42.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:79:0) after 1m0s
[23:39:42.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:79:0)
[23:39:42.568] Timer tick, asking Buildbucket for the build status
[23:39:42.628] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:42.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:80:0) after 1m0s
[23:40:42.658] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:80:0)
[23:40:42.658] Timer tick, asking Buildbucket for the build status
[23:40:42.765] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:42.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:81:0) after 1m0s
[23:41:42.789] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:81:0)
[23:41:42.789] Timer tick, asking Buildbucket for the build status
[23:41:42.857] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:42.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:82:0) after 1m0s
[23:42:42.922] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:82:0)
[23:42:42.922] Timer tick, asking Buildbucket for the build status
[23:42:43.024] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:43.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:83:0) after 1m0s
[23:43:43.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:83:0)
[23:43:43.022] Timer tick, asking Buildbucket for the build status
[23:43:43.741] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:43.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:84:0) after 1m0s
[23:44:43.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:84:0)
[23:44:43.832] Timer tick, asking Buildbucket for the build status
[23:44:43.918] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:43.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:85:0) after 1m0s
[23:45:43.973] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:85:0)
[23:45:43.974] Timer tick, asking Buildbucket for the build status
[23:45:44.073] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:44.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:86:0) after 1m0s
[23:46:44.100] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:86:0)
[23:46:44.100] Timer tick, asking Buildbucket for the build status
[23:46:44.165] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:44.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:87:0) after 1m0s
[23:47:44.182] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:87:0)
[23:47:44.182] Timer tick, asking Buildbucket for the build status
[23:47:44.484] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:44.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:88:0) after 1m0s
[23:48:44.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:88:0)
[23:48:44.499] Timer tick, asking Buildbucket for the build status
[23:48:44.605] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:44.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:89:0) after 1m0s
[23:49:44.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:89:0)
[23:49:44.727] Timer tick, asking Buildbucket for the build status
[23:49:44.867] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:44.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:90:0) after 1m0s
[23:50:44.896] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:90:0)
[23:50:44.896] Timer tick, asking Buildbucket for the build status
[23:50:44.974] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:44.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:91:0) after 1m0s
[23:51:44.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:91:0)
[23:51:44.958] Timer tick, asking Buildbucket for the build status
[23:51:45.146] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:45.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:92:0) after 1m0s
[23:52:45.206] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:92:0)
[23:52:45.206] Timer tick, asking Buildbucket for the build status
[23:52:45.360] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:45.360] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:93:0) after 1m0s
[23:53:45.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:93:0)
[23:53:45.417] Timer tick, asking Buildbucket for the build status
[23:53:45.694] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:45.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:94:0) after 1m0s
[23:54:45.723] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:94:0)
[23:54:45.723] Timer tick, asking Buildbucket for the build status
[23:54:45.791] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:45.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:95:0) after 1m0s
[23:55:45.819] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:95:0)
[23:55:45.819] Timer tick, asking Buildbucket for the build status
[23:55:46.169] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:46.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:96:0) after 1m0s
[23:56:46.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:96:0)
[23:56:46.204] Timer tick, asking Buildbucket for the build status
[23:56:46.442] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:46.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:97:0) after 1m0s
[23:57:46.467] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:97:0)
[23:57:46.467] Timer tick, asking Buildbucket for the build status
[23:57:46.752] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:46.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:98:0) after 1m0s
[23:58:46.842] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:98:0)
[23:58:46.842] Timer tick, asking Buildbucket for the build status
[23:58:47.001] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:47.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:99:0) after 1m0s
[23:59:47.060] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:99:0)
[23:59:47.060] Timer tick, asking Buildbucket for the build status
[23:59:47.133] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:47.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:100:0) after 1m0s
[00:00:47.234] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:100:0)
[00:00:47.234] Timer tick, asking Buildbucket for the build status
[00:00:47.428] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:47.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:101:0) after 1m0s
[00:01:47.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:101:0)
[00:01:47.454] Timer tick, asking Buildbucket for the build status
[00:01:47.534] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:47.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:102:0) after 1m0s
[00:02:47.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:102:0)
[00:02:47.588] Timer tick, asking Buildbucket for the build status
[00:02:47.832] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:47.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:103:0) after 1m0s
[00:03:47.870] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:103:0)
[00:03:47.870] Timer tick, asking Buildbucket for the build status
[00:03:48.018] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:48.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:104:0) after 1m0s
[00:04:48.046] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:104:0)
[00:04:48.046] Timer tick, asking Buildbucket for the build status
[00:04:48.196] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:48.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:105:0) after 1m0s
[00:05:48.253] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:105:0)
[00:05:48.253] Timer tick, asking Buildbucket for the build status
[00:05:48.483] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:48.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:106:0) after 1m0s
[00:06:48.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:106:0)
[00:06:48.554] Timer tick, asking Buildbucket for the build status
[00:06:48.637] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:48.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:107:0) after 1m0s
[00:07:48.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:107:0)
[00:07:48.712] Timer tick, asking Buildbucket for the build status
[00:07:48.832] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:48.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:108:0) after 1m0s
[00:08:48.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:108:0)
[00:08:48.848] Timer tick, asking Buildbucket for the build status
[00:08:48.986] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:48.986] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:109:0) after 1m0s
[00:09:50.074] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:109:0)
[00:09:50.074] Timer tick, asking Buildbucket for the build status
[00:09:50.169] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:50.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:110:0) after 1m0s
[00:10:50.250] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:110:0)
[00:10:50.250] Timer tick, asking Buildbucket for the build status
[00:10:51.325] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:51.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:111:0) after 1m0s
[00:11:51.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:111:0)
[00:11:51.422] Timer tick, asking Buildbucket for the build status
[00:11:51.508] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:51.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:112:0) after 1m0s
[00:12:51.569] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:112:0)
[00:12:51.569] Timer tick, asking Buildbucket for the build status
[00:12:51.886] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:51.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:113:0) after 1m0s
[00:13:51.957] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:113:0)
[00:13:51.957] Timer tick, asking Buildbucket for the build status
[00:13:52.021] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:52.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:114:0) after 1m0s
[00:14:52.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:114:0)
[00:14:52.053] Timer tick, asking Buildbucket for the build status
[00:14:52.125] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:52.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:115:0) after 1m0s
[00:15:52.167] Handling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:115:0)
[00:15:52.167] Timer tick, asking Buildbucket for the build status
[00:15:52.581] Build 8873764488840568896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:52.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9039194550862363856:116:0) after 1m0s
[00:16:50.085] Received PubSub notification, asking Buildbucket for the build status
[00:16:50.688] Build 8873764488840568896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:16:50.688] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1595722609244164",
"created_by": "project:chromium",
"created_ts": "1595715739362575",
"id": "8873764488840568896",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"811b6a4e37932e75ea8b59da96ffc11dd409ba8f\"}}",
"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-3-gf98\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 174, \"got_angle_revision\": \"df5ed61ddbcab119b059e6e5bd28a049b726766a\", \"got_dawn_revision\": \"d5a4775cfc77da29870286a217ea49e4ab3270a3\", \"got_nacl_revision\": \"fd20fafa8ec19700e12daa031bf792bae8b6bb21\", \"got_revision\": \"811b6a4e37932e75ea8b59da96ffc11dd409ba8f\", \"got_revision_cp\": \"refs/heads/master@{#791543}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"6a8a74986c357b0c6fa0dfd2b4b9230af8d39d1a\", \"got_v8_revision\": \"5cdec88a9e3e230528eeb9c34c68437a24db4b89\", \"got_v8_revision_cp\": \"refs/heads/8.6.230@{#1}\", \"got_webrtc_revision\": \"82e85676c3b35e721e8e61a1d089f4a0eca4750c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31782}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"811b6a4e37932e75ea8b59da96ffc11dd409ba8f\"}, \"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-3-gf98\"], \"image\": [\"chrome-win10-1703-20071600-faa8609efbc\"], \"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\": [\"5425-e4e3059\"], \"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": "1595715741442159",
"status": "COMPLETED",
"status_changed_ts": "1595722609669245",
"tags": [
"build_address:luci.chromium.ci/win-official/174",
"builder:win-official",
"buildset:commit/git/811b6a4e37932e75ea8b59da96ffc11dd409ba8f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/811b6a4e37932e75ea8b59da96ffc11dd409ba8f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9039194550862363856",
"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/8873764488840568896/+/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:4da0e36c1eb39210",
"user_agent:luci-scheduler"
],
"updated_ts": "1595722609676361",
"url": "https://ci.chromium.org/b/8873764488840568896",
"utcnow_ts": "1595722610676746"
}
}
[00:16:50.688] Invocation finished in 1h54m32.637004944s with status SUCCEEDED