[23:03:04.761] New invocation is queued and will start shortly
[23:03:06.844] Starting the invocation (attempt 1)
[23:03:06.890] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:03:06.891] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:03:06.891] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9038739000488118752",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"134db883242783170bc79cb07c288aca564de37c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9038739000488118752",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/134db883242783170bc79cb07c288aca564de37c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/134db883242783170bc79cb07c288aca564de37c",
"gitiles_ref:refs/heads/master"
]
}
[23:03:07.889] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596150186987876",
"id": "8873308937488363904",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"134db883242783170bc79cb07c288aca564de37c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596150187594386",
"tags": [
"build_address:luci.chromium.ci/win-official/206",
"builder:win-official",
"buildset:commit/git/134db883242783170bc79cb07c288aca564de37c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/134db883242783170bc79cb07c288aca564de37c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038739000488118752",
"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/8873308937488363904/+/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": "1596150187594642",
"url": "https://ci.chromium.org/b/8873308937488363904",
"utcnow_ts": "1596150187878751"
}
}
[23:03:07.889] Task URL: https://ci.chromium.org/b/8873308937488363904
[23:03:07.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:2:0) after 1m0s
[23:03:14.331] Received PubSub notification, asking Buildbucket for the build status
[23:03:14.452] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:07.949] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:2:0)
[23:04:07.949] Timer tick, asking Buildbucket for the build status
[23:04:08.264] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:08.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:4:0) after 1m0s
[23:05:08.300] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:4:0)
[23:05:08.300] Timer tick, asking Buildbucket for the build status
[23:05:08.445] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:08.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:5:0) after 1m0s
[23:06:08.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:5:0)
[23:06:08.430] Timer tick, asking Buildbucket for the build status
[23:06:08.534] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:08.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:6:0) after 1m0s
[23:07:08.600] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:6:0)
[23:07:08.600] Timer tick, asking Buildbucket for the build status
[23:07:08.681] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:08.681] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:7:0) after 1m0s
[23:08:08.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:7:0)
[23:08:08.699] Timer tick, asking Buildbucket for the build status
[23:08:08.790] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:08.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:8:0) after 1m0s
[23:09:08.808] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:8:0)
[23:09:08.808] Timer tick, asking Buildbucket for the build status
[23:09:09.253] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:09.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:9:0) after 1m0s
[23:10:09.589] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:9:0)
[23:10:09.589] Timer tick, asking Buildbucket for the build status
[23:10:09.818] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:09.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:10:0) after 1m0s
[23:11:10.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:10:0)
[23:11:10.233] Timer tick, asking Buildbucket for the build status
[23:11:10.933] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:10.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:11:0) after 1m0s
[23:12:11.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:11:0)
[23:12:11.116] Timer tick, asking Buildbucket for the build status
[23:12:11.489] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:11.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:12:0) after 1m0s
[23:13:11.500] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:12:0)
[23:13:11.500] Timer tick, asking Buildbucket for the build status
[23:13:11.940] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:11.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:13:0) after 1m0s
[23:14:12.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:13:0)
[23:14:12.036] Timer tick, asking Buildbucket for the build status
[23:14:12.226] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:12.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:14:0) after 1m0s
[23:15:12.291] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:14:0)
[23:15:12.291] Timer tick, asking Buildbucket for the build status
[23:15:12.414] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:12.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:15:0) after 1m0s
[23:16:12.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:15:0)
[23:16:12.648] Timer tick, asking Buildbucket for the build status
[23:16:12.722] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:12.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:16:0) after 1m0s
[23:17:13.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:16:0)
[23:17:13.124] Timer tick, asking Buildbucket for the build status
[23:17:13.465] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:13.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:17:0) after 1m0s
[23:18:13.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:17:0)
[23:18:13.909] Timer tick, asking Buildbucket for the build status
[23:18:14.205] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:14.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:18:0) after 1m0s
[23:19:14.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:18:0)
[23:19:14.719] Timer tick, asking Buildbucket for the build status
[23:19:14.992] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:14.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:19:0) after 1m0s
[23:20:15.314] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:19:0)
[23:20:15.314] Timer tick, asking Buildbucket for the build status
[23:20:15.407] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:15.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:20:0) after 1m0s
[23:21:15.783] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:20:0)
[23:21:15.783] Timer tick, asking Buildbucket for the build status
[23:21:16.055] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:16.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:21:0) after 1m0s
[23:22:16.061] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:21:0)
[23:22:16.061] Timer tick, asking Buildbucket for the build status
[23:22:17.227] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:17.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:22:0) after 1m0s
[23:23:17.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:22:0)
[23:23:17.366] Timer tick, asking Buildbucket for the build status
[23:23:17.750] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:17.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:23:0) after 1m0s
[23:24:17.844] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:23:0)
[23:24:17.844] Timer tick, asking Buildbucket for the build status
[23:24:17.930] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:17.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:24:0) after 1m0s
[23:25:18.136] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:24:0)
[23:25:18.136] Timer tick, asking Buildbucket for the build status
[23:25:18.250] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:18.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:25:0) after 1m0s
[23:26:18.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:25:0)
[23:26:18.719] Timer tick, asking Buildbucket for the build status
[23:26:18.812] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:18.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:26:0) after 1m0s
[23:27:19.003] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:26:0)
[23:27:19.003] Timer tick, asking Buildbucket for the build status
[23:27:19.071] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:19.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:27:0) after 1m0s
[23:28:19.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:27:0)
[23:28:19.268] Timer tick, asking Buildbucket for the build status
[23:28:19.381] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:19.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:28:0) after 1m0s
[23:29:20.535] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:28:0)
[23:29:20.535] Timer tick, asking Buildbucket for the build status
[23:29:20.669] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:20.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:29:0) after 1m0s
[23:30:20.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:29:0)
[23:30:20.742] Timer tick, asking Buildbucket for the build status
[23:30:20.831] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:20.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:30:0) after 1m0s
[23:31:20.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:30:0)
[23:31:20.983] Timer tick, asking Buildbucket for the build status
[23:31:21.061] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:21.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:31:0) after 1m0s
[23:32:21.073] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:31:0)
[23:32:21.073] Timer tick, asking Buildbucket for the build status
[23:32:21.171] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:21.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:32:0) after 1m0s
[23:33:21.229] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:32:0)
[23:33:21.229] Timer tick, asking Buildbucket for the build status
[23:33:21.423] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:21.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:33:0) after 1m0s
[23:34:21.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:33:0)
[23:34:21.470] Timer tick, asking Buildbucket for the build status
[23:34:24.011] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:24.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:34:0) after 1m0s
[23:35:24.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:34:0)
[23:35:24.142] Timer tick, asking Buildbucket for the build status
[23:35:24.245] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:24.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:35:0) after 1m0s
[23:36:24.298] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:35:0)
[23:36:24.298] Timer tick, asking Buildbucket for the build status
[23:36:24.377] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:24.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:36:0) after 1m0s
[23:37:24.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:36:0)
[23:37:24.507] Timer tick, asking Buildbucket for the build status
[23:37:24.617] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:24.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:37:0) after 1m0s
[23:38:24.850] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:37:0)
[23:38:24.850] Timer tick, asking Buildbucket for the build status
[23:38:25.098] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:25.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:38:0) after 1m0s
[23:39:25.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:38:0)
[23:39:25.208] Timer tick, asking Buildbucket for the build status
[23:39:25.295] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:25.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:39:0) after 1m0s
[23:40:25.286] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:39:0)
[23:40:25.286] Timer tick, asking Buildbucket for the build status
[23:40:25.412] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:25.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:40:0) after 1m0s
[23:41:25.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:40:0)
[23:41:25.449] Timer tick, asking Buildbucket for the build status
[23:41:25.638] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:25.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:41:0) after 1m0s
[23:42:25.651] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:41:0)
[23:42:25.651] Timer tick, asking Buildbucket for the build status
[23:42:25.739] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:25.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:42:0) after 1m0s
[23:43:25.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:42:0)
[23:43:25.773] Timer tick, asking Buildbucket for the build status
[23:43:26.082] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:26.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:43:0) after 1m0s
[23:44:26.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:43:0)
[23:44:26.207] Timer tick, asking Buildbucket for the build status
[23:44:26.429] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:26.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:44:0) after 1m0s
[23:45:26.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:44:0)
[23:45:26.518] Timer tick, asking Buildbucket for the build status
[23:45:26.870] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:26.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:45:0) after 1m0s
[23:46:26.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:45:0)
[23:46:26.935] Timer tick, asking Buildbucket for the build status
[23:46:27.074] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:27.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:46:0) after 1m0s
[23:47:27.055] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:46:0)
[23:47:27.055] Timer tick, asking Buildbucket for the build status
[23:47:27.184] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:27.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:47:0) after 1m0s
[23:48:27.208] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:47:0)
[23:48:27.208] Timer tick, asking Buildbucket for the build status
[23:48:27.359] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:27.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:48:0) after 1m0s
[23:49:27.371] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:48:0)
[23:49:27.371] Timer tick, asking Buildbucket for the build status
[23:49:27.631] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:27.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:49:0) after 1m0s
[23:50:27.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:49:0)
[23:50:27.657] Timer tick, asking Buildbucket for the build status
[23:50:27.772] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:27.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:50:0) after 1m0s
[23:51:27.897] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:50:0)
[23:51:27.897] Timer tick, asking Buildbucket for the build status
[23:51:28.441] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:28.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:51:0) after 1m0s
[23:52:28.489] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:51:0)
[23:52:28.489] Timer tick, asking Buildbucket for the build status
[23:52:28.677] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:28.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:52:0) after 1m0s
[23:53:28.749] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:52:0)
[23:53:28.749] Timer tick, asking Buildbucket for the build status
[23:53:29.033] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:29.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:53:0) after 1m0s
[23:54:29.059] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:53:0)
[23:54:29.059] Timer tick, asking Buildbucket for the build status
[23:54:29.179] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:29.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:54:0) after 1m0s
[23:55:29.209] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:54:0)
[23:55:29.209] Timer tick, asking Buildbucket for the build status
[23:55:29.282] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:29.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:55:0) after 1m0s
[23:56:29.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:55:0)
[23:56:29.318] Timer tick, asking Buildbucket for the build status
[23:56:29.373] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:29.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:56:0) after 1m0s
[23:57:29.425] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:56:0)
[23:57:29.425] Timer tick, asking Buildbucket for the build status
[23:57:29.604] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:29.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:57:0) after 1m0s
[23:58:29.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:57:0)
[23:58:29.631] Timer tick, asking Buildbucket for the build status
[23:58:30.713] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:30.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:58:0) after 1m0s
[23:59:30.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:58:0)
[23:59:30.885] Timer tick, asking Buildbucket for the build status
[23:59:31.052] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:31.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:59:0) after 1m0s
[00:00:31.186] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:59:0)
[00:00:31.186] Timer tick, asking Buildbucket for the build status
[00:00:31.341] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:31.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:60:0) after 1m0s
[00:01:31.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:60:0)
[00:01:31.417] Timer tick, asking Buildbucket for the build status
[00:01:31.601] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:31.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:61:0) after 1m0s
[00:02:31.644] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:61:0)
[00:02:31.644] Timer tick, asking Buildbucket for the build status
[00:02:31.749] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:31.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:62:0) after 1m0s
[00:03:31.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:62:0)
[00:03:31.804] Timer tick, asking Buildbucket for the build status
[00:03:32.010] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:32.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:63:0) after 1m0s
[00:04:32.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:63:0)
[00:04:32.096] Timer tick, asking Buildbucket for the build status
[00:04:32.172] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:32.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:64:0) after 1m0s
[00:05:32.406] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:64:0)
[00:05:32.406] Timer tick, asking Buildbucket for the build status
[00:05:32.550] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:32.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:65:0) after 1m0s
[00:06:32.684] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:65:0)
[00:06:32.684] Timer tick, asking Buildbucket for the build status
[00:06:32.796] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:32.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:66:0) after 1m0s
[00:07:32.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:66:0)
[00:07:32.891] Timer tick, asking Buildbucket for the build status
[00:07:33.054] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:33.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:67:0) after 1m0s
[00:08:33.111] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:67:0)
[00:08:33.111] Timer tick, asking Buildbucket for the build status
[00:08:33.284] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:33.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:68:0) after 1m0s
[00:09:33.322] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:68:0)
[00:09:33.322] Timer tick, asking Buildbucket for the build status
[00:09:33.441] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:33.441] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:69:0) after 1m0s
[00:10:33.471] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:69:0)
[00:10:33.471] Timer tick, asking Buildbucket for the build status
[00:10:33.565] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:33.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:70:0) after 1m0s
[00:11:33.620] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:70:0)
[00:11:33.620] Timer tick, asking Buildbucket for the build status
[00:11:33.710] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:33.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:71:0) after 1m0s
[00:12:33.805] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:71:0)
[00:12:33.805] Timer tick, asking Buildbucket for the build status
[00:12:33.888] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:33.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:72:0) after 1m0s
[00:13:33.915] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:72:0)
[00:13:33.915] Timer tick, asking Buildbucket for the build status
[00:13:34.129] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:34.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:73:0) after 1m0s
[00:14:34.191] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:73:0)
[00:14:34.191] Timer tick, asking Buildbucket for the build status
[00:14:34.285] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:34.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:74:0) after 1m0s
[00:15:34.322] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:74:0)
[00:15:34.322] Timer tick, asking Buildbucket for the build status
[00:15:34.531] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:34.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:75:0) after 1m0s
[00:16:34.593] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:75:0)
[00:16:34.593] Timer tick, asking Buildbucket for the build status
[00:16:34.705] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:34.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:76:0) after 1m0s
[00:17:34.735] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:76:0)
[00:17:34.736] Timer tick, asking Buildbucket for the build status
[00:17:34.820] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:34.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:77:0) after 1m0s
[00:18:34.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:77:0)
[00:18:34.899] Timer tick, asking Buildbucket for the build status
[00:18:35.059] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:35.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:78:0) after 1m0s
[00:19:35.101] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:78:0)
[00:19:35.101] Timer tick, asking Buildbucket for the build status
[00:19:35.198] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:35.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:79:0) after 1m0s
[00:20:35.270] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:79:0)
[00:20:35.270] Timer tick, asking Buildbucket for the build status
[00:20:35.488] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:35.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:80:0) after 1m0s
[00:21:35.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:80:0)
[00:21:35.560] Timer tick, asking Buildbucket for the build status
[00:21:35.698] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:35.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:81:0) after 1m0s
[00:22:35.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:81:0)
[00:22:35.729] Timer tick, asking Buildbucket for the build status
[00:22:35.883] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:35.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:82:0) after 1m0s
[00:23:35.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:82:0)
[00:23:35.975] Timer tick, asking Buildbucket for the build status
[00:23:36.220] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:36.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:83:0) after 1m0s
[00:24:36.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:83:0)
[00:24:36.238] Timer tick, asking Buildbucket for the build status
[00:24:36.346] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:36.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:84:0) after 1m0s
[00:25:36.382] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:84:0)
[00:25:36.382] Timer tick, asking Buildbucket for the build status
[00:25:36.452] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:36.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:85:0) after 1m0s
[00:26:36.529] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:85:0)
[00:26:36.529] Timer tick, asking Buildbucket for the build status
[00:26:36.632] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:36.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:86:0) after 1m0s
[00:27:36.964] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:86:0)
[00:27:36.964] Timer tick, asking Buildbucket for the build status
[00:27:37.054] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:37.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:87:0) after 1m0s
[00:28:37.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:87:0)
[00:28:37.348] Timer tick, asking Buildbucket for the build status
[00:28:39.302] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:39.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:88:0) after 1m0s
[00:29:39.338] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:88:0)
[00:29:39.338] Timer tick, asking Buildbucket for the build status
[00:29:39.415] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:39.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:89:0) after 1m0s
[00:30:39.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:89:0)
[00:30:39.491] Timer tick, asking Buildbucket for the build status
[00:30:39.592] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:39.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:90:0) after 1m0s
[00:31:39.636] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:90:0)
[00:31:39.636] Timer tick, asking Buildbucket for the build status
[00:31:39.793] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:39.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:91:0) after 1m0s
[00:32:39.889] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:91:0)
[00:32:39.889] Timer tick, asking Buildbucket for the build status
[00:32:39.945] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:39.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:92:0) after 1m0s
[00:33:40.168] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:92:0)
[00:33:40.168] Timer tick, asking Buildbucket for the build status
[00:33:40.336] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:40.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:93:0) after 1m0s
[00:34:40.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:93:0)
[00:34:40.775] Timer tick, asking Buildbucket for the build status
[00:34:41.254] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:41.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:94:0) after 1m0s
[00:35:41.378] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:94:0)
[00:35:41.378] Timer tick, asking Buildbucket for the build status
[00:35:41.537] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:41.537] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:95:0) after 1m0s
[00:36:41.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:95:0)
[00:36:41.759] Timer tick, asking Buildbucket for the build status
[00:36:41.969] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:41.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:96:0) after 1m0s
[00:37:42.181] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:96:0)
[00:37:42.181] Timer tick, asking Buildbucket for the build status
[00:37:42.292] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:42.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:97:0) after 1m0s
[00:38:42.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:97:0)
[00:38:42.449] Timer tick, asking Buildbucket for the build status
[00:38:42.696] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:42.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:98:0) after 1m0s
[00:39:42.764] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:98:0)
[00:39:42.764] Timer tick, asking Buildbucket for the build status
[00:39:42.896] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:42.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:99:0) after 1m0s
[00:40:42.934] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:99:0)
[00:40:42.934] Timer tick, asking Buildbucket for the build status
[00:40:43.042] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:43.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:100:0) after 1m0s
[00:41:43.096] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:100:0)
[00:41:43.096] Timer tick, asking Buildbucket for the build status
[00:41:43.252] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:43.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:101:0) after 1m0s
[00:42:43.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:101:0)
[00:42:43.321] Timer tick, asking Buildbucket for the build status
[00:42:44.115] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:44.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:102:0) after 1m0s
[00:43:44.132] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:102:0)
[00:43:44.132] Timer tick, asking Buildbucket for the build status
[00:43:44.286] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:44.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:103:0) after 1m0s
[00:44:44.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:103:0)
[00:44:44.273] Timer tick, asking Buildbucket for the build status
[00:44:44.363] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:44.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:104:0) after 1m0s
[00:45:44.372] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:104:0)
[00:45:44.372] Timer tick, asking Buildbucket for the build status
[00:45:44.455] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:44.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:105:0) after 1m0s
[00:46:44.547] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:105:0)
[00:46:44.547] Timer tick, asking Buildbucket for the build status
[00:46:44.655] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:44.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:106:0) after 1m0s
[00:47:44.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:106:0)
[00:47:44.760] Timer tick, asking Buildbucket for the build status
[00:47:45.079] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:45.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:107:0) after 1m0s
[00:48:45.133] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:107:0)
[00:48:45.133] Timer tick, asking Buildbucket for the build status
[00:48:45.590] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:45.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:108:0) after 1m0s
[00:49:45.777] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:108:0)
[00:49:45.777] Timer tick, asking Buildbucket for the build status
[00:49:45.872] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:45.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:109:0) after 1m0s
[00:50:45.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:109:0)
[00:50:45.851] Timer tick, asking Buildbucket for the build status
[00:50:45.941] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:45.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:110:0) after 1m0s
[00:51:46.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:110:0)
[00:51:46.043] Timer tick, asking Buildbucket for the build status
[00:51:46.213] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:46.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:111:0) after 1m0s
[00:52:46.255] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:111:0)
[00:52:46.255] Timer tick, asking Buildbucket for the build status
[00:52:46.419] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:46.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:112:0) after 1m0s
[00:53:46.453] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:112:0)
[00:53:46.453] Timer tick, asking Buildbucket for the build status
[00:53:46.738] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:46.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:113:0) after 1m0s
[00:54:46.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:113:0)
[00:54:46.821] Timer tick, asking Buildbucket for the build status
[00:54:46.937] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:46.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:114:0) after 1m0s
[00:55:47.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:114:0)
[00:55:47.034] Timer tick, asking Buildbucket for the build status
[00:55:47.133] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:47.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:115:0) after 1m0s
[00:56:47.149] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:115:0)
[00:56:47.149] Timer tick, asking Buildbucket for the build status
[00:56:47.234] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:47.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:116:0) after 1m0s
[00:57:47.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:116:0)
[00:57:47.309] Timer tick, asking Buildbucket for the build status
[00:57:47.469] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:47.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:117:0) after 1m0s
[00:58:47.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:117:0)
[00:58:47.573] Timer tick, asking Buildbucket for the build status
[00:58:47.698] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:47.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:118:0) after 1m0s
[00:59:47.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:118:0)
[00:59:47.811] Timer tick, asking Buildbucket for the build status
[00:59:47.892] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:47.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:119:0) after 1m0s
[01:00:48.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:119:0)
[01:00:48.173] Timer tick, asking Buildbucket for the build status
[01:00:48.257] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:48.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:120:0) after 1m0s
[01:01:48.323] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:120:0)
[01:01:48.324] Timer tick, asking Buildbucket for the build status
[01:01:48.619] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:48.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:121:0) after 1m0s
[01:02:48.673] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:121:0)
[01:02:48.673] Timer tick, asking Buildbucket for the build status
[01:02:49.414] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:49.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:122:0) after 1m0s
[01:03:49.573] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:122:0)
[01:03:49.573] Timer tick, asking Buildbucket for the build status
[01:03:49.850] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:49.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:123:0) after 1m0s
[01:04:49.923] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:123:0)
[01:04:49.923] Timer tick, asking Buildbucket for the build status
[01:04:50.636] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:50.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:124:0) after 1m0s
[01:05:50.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:124:0)
[01:05:50.719] Timer tick, asking Buildbucket for the build status
[01:05:50.924] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:50.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:125:0) after 1m0s
[01:06:50.976] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:125:0)
[01:06:50.976] Timer tick, asking Buildbucket for the build status
[01:06:51.064] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:51.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:126:0) after 1m0s
[01:07:51.082] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:126:0)
[01:07:51.082] Timer tick, asking Buildbucket for the build status
[01:07:51.155] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:51.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:127:0) after 1m0s
[01:08:51.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:127:0)
[01:08:51.280] Timer tick, asking Buildbucket for the build status
[01:08:51.614] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:51.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:128:0) after 1m0s
[01:09:51.657] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:128:0)
[01:09:51.657] Timer tick, asking Buildbucket for the build status
[01:09:52.486] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:52.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:129:0) after 1m0s
[01:10:52.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:129:0)
[01:10:52.560] Timer tick, asking Buildbucket for the build status
[01:10:52.766] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:52.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:130:0) after 1m0s
[01:11:52.782] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:130:0)
[01:11:52.782] Timer tick, asking Buildbucket for the build status
[01:11:52.895] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:52.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:131:0) after 1m0s
[01:12:52.910] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:131:0)
[01:12:52.910] Timer tick, asking Buildbucket for the build status
[01:12:52.998] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:52.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:132:0) after 1m0s
[01:13:53.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:132:0)
[01:13:53.102] Timer tick, asking Buildbucket for the build status
[01:13:53.274] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:53.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:133:0) after 1m0s
[01:14:53.302] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:133:0)
[01:14:53.302] Timer tick, asking Buildbucket for the build status
[01:14:53.644] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:53.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:134:0) after 1m0s
[01:15:53.676] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:134:0)
[01:15:53.676] Timer tick, asking Buildbucket for the build status
[01:15:54.148] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:54.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:135:0) after 1m0s
[01:16:54.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:135:0)
[01:16:54.493] Timer tick, asking Buildbucket for the build status
[01:16:54.783] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:54.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:136:0) after 1m0s
[01:17:54.911] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:136:0)
[01:17:54.911] Timer tick, asking Buildbucket for the build status
[01:17:54.994] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:54.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:137:0) after 1m0s
[01:18:55.125] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:137:0)
[01:18:55.125] Timer tick, asking Buildbucket for the build status
[01:18:55.607] Build 8873308937488363904: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:55.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038739000488118752:138:0) after 1m0s
[01:19:53.459] Received PubSub notification, asking Buildbucket for the build status
[01:19:53.563] Build 8873308937488363904: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:19:53.563] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596158392304248",
"created_by": "project:chromium",
"created_ts": "1596150186987876",
"id": "8873308937488363904",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"134db883242783170bc79cb07c288aca564de37c\"}}",
"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-0-fdfa\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 206, \"got_angle_revision\": \"dcc562158c8eecbfa5326e1cc7b28e9f4968a82f\", \"got_dawn_revision\": \"05863e62f3dc20abb3673ce2df77ca3fbe1d6e13\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"134db883242783170bc79cb07c288aca564de37c\", \"got_revision_cp\": \"refs/heads/master@{#793404}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"d9a3a085f8f6c4c1caa4f95e5c1c0501b5b38623\", \"got_v8_revision\": \"edf39aefdc3a174051391c62204591520ba5921b\", \"got_v8_revision_cp\": \"refs/heads/8.6.292@{#1}\", \"got_webrtc_revision\": \"0c9204c1839e5c219fc90ecdf5a2d536f635a47f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31813}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"134db883242783170bc79cb07c288aca564de37c\"}, \"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-0-fdfa\"], \"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\": [\"5436-adf7f8a\"], \"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": "1596150193509784",
"status": "COMPLETED",
"status_changed_ts": "1596158393020360",
"tags": [
"build_address:luci.chromium.ci/win-official/206",
"builder:win-official",
"buildset:commit/git/134db883242783170bc79cb07c288aca564de37c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/134db883242783170bc79cb07c288aca564de37c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038739000488118752",
"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/8873308937488363904/+/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:4dbac890af814f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1596158393027677",
"url": "https://ci.chromium.org/b/8873308937488363904",
"utcnow_ts": "1596158393549463"
}
}
[01:19:53.563] Invocation finished in 2h16m48.821546426s with status SUCCEEDED