[20:14:46.272] New invocation is queued and will start shortly
[20:14:47.366] Starting the invocation (attempt 1)
[20:14:47.410] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:14:47.411] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:14:47.411] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026337805122724848",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0249ee0643009646e3d59cf0d7d6ec674a16b2d2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9026337805122724848",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/0249ee0643009646e3d59cf0d7d6ec674a16b2d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0249ee0643009646e3d59cf0d7d6ec674a16b2d2",
"gitiles_ref:refs/heads/master"
]
}
[20:14:48.413] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1607976887545739",
"id": "8860907743124088480",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0249ee0643009646e3d59cf0d7d6ec674a16b2d2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607976887977727",
"tags": [
"build_address:luci.chromium.ci/win-official/1169",
"builder:win-official",
"buildset:commit/git/0249ee0643009646e3d59cf0d7d6ec674a16b2d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0249ee0643009646e3d59cf0d7d6ec674a16b2d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026337805122724848",
"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/8860907743124088480/+/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": "1607976887977847",
"url": "https://ci.chromium.org/b/8860907743124088480",
"utcnow_ts": "1607976888402881"
}
}
[20:14:48.413] Task URL: https://ci.chromium.org/b/8860907743124088480
[20:14:48.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:2:0) after 1m0s
[20:15:26.801] Received PubSub notification, asking Buildbucket for the build status
[20:15:26.964] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:48.460] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:2:0)
[20:15:48.460] Timer tick, asking Buildbucket for the build status
[20:15:48.573] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:48.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:4:0) after 1m0s
[20:16:48.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:4:0)
[20:16:48.604] Timer tick, asking Buildbucket for the build status
[20:16:48.960] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:48.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:5:0) after 1m0s
[20:17:49.001] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:5:0)
[20:17:49.001] Timer tick, asking Buildbucket for the build status
[20:17:49.103] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:49.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:6:0) after 1m0s
[20:18:49.084] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:6:0)
[20:18:49.084] Timer tick, asking Buildbucket for the build status
[20:18:49.463] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:49.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:7:0) after 1m0s
[20:19:49.521] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:7:0)
[20:19:49.521] Timer tick, asking Buildbucket for the build status
[20:19:49.647] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:49.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:8:0) after 1m0s
[20:20:49.686] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:8:0)
[20:20:49.686] Timer tick, asking Buildbucket for the build status
[20:20:49.791] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:49.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:9:0) after 1m0s
[20:21:49.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:9:0)
[20:21:49.898] Timer tick, asking Buildbucket for the build status
[20:21:50.116] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:50.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:10:0) after 1m0s
[20:22:50.139] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:10:0)
[20:22:50.139] Timer tick, asking Buildbucket for the build status
[20:22:50.229] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:50.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:11:0) after 1m0s
[20:23:50.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:11:0)
[20:23:50.369] Timer tick, asking Buildbucket for the build status
[20:23:50.619] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:50.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:12:0) after 1m0s
[20:24:50.675] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:12:0)
[20:24:50.675] Timer tick, asking Buildbucket for the build status
[20:24:50.773] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:50.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:13:0) after 1m0s
[20:25:50.821] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:13:0)
[20:25:50.821] Timer tick, asking Buildbucket for the build status
[20:25:50.907] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:50.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:14:0) after 1m0s
[20:26:50.971] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:14:0)
[20:26:50.971] Timer tick, asking Buildbucket for the build status
[20:26:51.071] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:51.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:15:0) after 1m0s
[20:27:51.195] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:15:0)
[20:27:51.195] Timer tick, asking Buildbucket for the build status
[20:27:51.325] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:51.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:16:0) after 1m0s
[20:28:51.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:16:0)
[20:28:51.409] Timer tick, asking Buildbucket for the build status
[20:28:51.720] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:51.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:17:0) after 1m0s
[20:29:51.762] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:17:0)
[20:29:51.762] Timer tick, asking Buildbucket for the build status
[20:29:51.868] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:51.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:18:0) after 1m0s
[20:30:51.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:18:0)
[20:30:51.912] Timer tick, asking Buildbucket for the build status
[20:30:51.989] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:51.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:19:0) after 1m0s
[20:31:52.013] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:19:0)
[20:31:52.013] Timer tick, asking Buildbucket for the build status
[20:31:52.072] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:52.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:20:0) after 1m0s
[20:32:52.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:20:0)
[20:32:52.102] Timer tick, asking Buildbucket for the build status
[20:32:52.166] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:52.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:21:0) after 1m0s
[20:33:52.204] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:21:0)
[20:33:52.204] Timer tick, asking Buildbucket for the build status
[20:33:52.272] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:52.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:22:0) after 1m0s
[20:34:52.311] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:22:0)
[20:34:52.311] Timer tick, asking Buildbucket for the build status
[20:34:52.412] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:52.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:23:0) after 1m0s
[20:35:52.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:23:0)
[20:35:52.452] Timer tick, asking Buildbucket for the build status
[20:35:52.660] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:52.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:24:0) after 1m0s
[20:36:52.753] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:24:0)
[20:36:52.753] Timer tick, asking Buildbucket for the build status
[20:36:53.063] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:53.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:25:0) after 1m0s
[20:37:53.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:25:0)
[20:37:53.158] Timer tick, asking Buildbucket for the build status
[20:37:53.269] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:53.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:26:0) after 1m0s
[20:38:53.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:26:0)
[20:38:53.316] Timer tick, asking Buildbucket for the build status
[20:38:53.419] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:53.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:27:0) after 1m0s
[20:39:53.419] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:27:0)
[20:39:53.419] Timer tick, asking Buildbucket for the build status
[20:39:53.492] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:53.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:28:0) after 1m0s
[20:40:55.306] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:28:0)
[20:40:55.306] Timer tick, asking Buildbucket for the build status
[20:40:55.362] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:55.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:29:0) after 1m0s
[20:41:55.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:29:0)
[20:41:55.423] Timer tick, asking Buildbucket for the build status
[20:41:55.778] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:55.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:30:0) after 1m0s
[20:42:55.926] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:30:0)
[20:42:55.926] Timer tick, asking Buildbucket for the build status
[20:42:56.133] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:56.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:31:0) after 1m0s
[20:43:56.156] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:31:0)
[20:43:56.156] Timer tick, asking Buildbucket for the build status
[20:43:56.415] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:56.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:32:0) after 1m0s
[20:44:56.508] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:32:0)
[20:44:56.508] Timer tick, asking Buildbucket for the build status
[20:44:56.814] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:56.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:33:0) after 1m0s
[20:45:56.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:33:0)
[20:45:56.912] Timer tick, asking Buildbucket for the build status
[20:45:56.991] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:56.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:34:0) after 1m0s
[20:46:56.994] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:34:0)
[20:46:56.994] Timer tick, asking Buildbucket for the build status
[20:46:57.527] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:57.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:35:0) after 1m0s
[20:47:57.574] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:35:0)
[20:47:57.574] Timer tick, asking Buildbucket for the build status
[20:47:57.638] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:57.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:36:0) after 1m0s
[20:48:57.668] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:36:0)
[20:48:57.668] Timer tick, asking Buildbucket for the build status
[20:48:57.739] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:57.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:37:0) after 1m0s
[20:49:57.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:37:0)
[20:49:57.744] Timer tick, asking Buildbucket for the build status
[20:49:57.851] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:57.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:38:0) after 1m0s
[20:50:57.884] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:38:0)
[20:50:57.884] Timer tick, asking Buildbucket for the build status
[20:50:57.983] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:57.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:39:0) after 1m0s
[20:51:57.969] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:39:0)
[20:51:57.969] Timer tick, asking Buildbucket for the build status
[20:51:58.064] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:58.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:40:0) after 1m0s
[20:52:58.073] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:40:0)
[20:52:58.073] Timer tick, asking Buildbucket for the build status
[20:52:58.275] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:58.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:41:0) after 1m0s
[20:53:58.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:41:0)
[20:53:58.309] Timer tick, asking Buildbucket for the build status
[20:53:58.376] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:58.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:42:0) after 1m0s
[20:54:58.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:42:0)
[20:54:58.383] Timer tick, asking Buildbucket for the build status
[20:54:58.491] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:58.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:43:0) after 1m0s
[20:55:58.554] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:43:0)
[20:55:58.554] Timer tick, asking Buildbucket for the build status
[20:55:58.637] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:58.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:44:0) after 1m0s
[20:56:58.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:44:0)
[20:56:58.647] Timer tick, asking Buildbucket for the build status
[20:56:58.702] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:58.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:45:0) after 1m0s
[20:57:58.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:45:0)
[20:57:58.700] Timer tick, asking Buildbucket for the build status
[20:57:58.773] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:58.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:46:0) after 1m0s
[20:58:58.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:46:0)
[20:58:58.852] Timer tick, asking Buildbucket for the build status
[20:58:59.033] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:59.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:47:0) after 1m0s
[20:59:59.076] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:47:0)
[20:59:59.076] Timer tick, asking Buildbucket for the build status
[20:59:59.457] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:59.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:48:0) after 1m0s
[21:00:59.572] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:48:0)
[21:00:59.572] Timer tick, asking Buildbucket for the build status
[21:00:59.933] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:59.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:49:0) after 1m0s
[21:02:00.316] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:49:0)
[21:02:00.316] Timer tick, asking Buildbucket for the build status
[21:02:00.524] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:00.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:50:0) after 1m0s
[21:03:00.875] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:50:0)
[21:03:00.875] Timer tick, asking Buildbucket for the build status
[21:03:00.957] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:00.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:51:0) after 1m0s
[21:04:01.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:51:0)
[21:04:01.431] Timer tick, asking Buildbucket for the build status
[21:04:01.513] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:01.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:52:0) after 1m0s
[21:05:02.669] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:52:0)
[21:05:02.669] Timer tick, asking Buildbucket for the build status
[21:05:02.829] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:02.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:53:0) after 1m0s
[21:06:02.935] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:53:0)
[21:06:02.935] Timer tick, asking Buildbucket for the build status
[21:06:03.010] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:03.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:54:0) after 1m0s
[21:07:03.173] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:54:0)
[21:07:03.173] Timer tick, asking Buildbucket for the build status
[21:07:03.274] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:03.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:55:0) after 1m0s
[21:08:03.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:55:0)
[21:08:03.444] Timer tick, asking Buildbucket for the build status
[21:08:03.567] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:03.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:56:0) after 1m0s
[21:09:03.631] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:56:0)
[21:09:03.631] Timer tick, asking Buildbucket for the build status
[21:09:03.733] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:03.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:57:0) after 1m0s
[21:10:03.853] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:57:0)
[21:10:03.853] Timer tick, asking Buildbucket for the build status
[21:10:03.979] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:03.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:58:0) after 1m0s
[21:11:03.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:58:0)
[21:11:03.999] Timer tick, asking Buildbucket for the build status
[21:11:04.063] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:04.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:59:0) after 1m0s
[21:12:04.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:59:0)
[21:12:04.110] Timer tick, asking Buildbucket for the build status
[21:12:04.277] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:04.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:60:0) after 1m0s
[21:13:04.335] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:60:0)
[21:13:04.335] Timer tick, asking Buildbucket for the build status
[21:13:04.389] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:04.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:61:0) after 1m0s
[21:14:04.417] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:61:0)
[21:14:04.417] Timer tick, asking Buildbucket for the build status
[21:14:04.545] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:04.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:62:0) after 1m0s
[21:15:04.641] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:62:0)
[21:15:04.641] Timer tick, asking Buildbucket for the build status
[21:15:05.041] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:05.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:63:0) after 1m0s
[21:16:05.179] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:63:0)
[21:16:05.179] Timer tick, asking Buildbucket for the build status
[21:16:05.270] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:05.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:64:0) after 1m0s
[21:17:05.349] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:64:0)
[21:17:05.349] Timer tick, asking Buildbucket for the build status
[21:17:05.421] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:05.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:65:0) after 1m0s
[21:18:05.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:65:0)
[21:18:05.510] Timer tick, asking Buildbucket for the build status
[21:18:05.638] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:05.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:66:0) after 1m0s
[21:19:05.696] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:66:0)
[21:19:05.696] Timer tick, asking Buildbucket for the build status
[21:19:05.819] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:05.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:67:0) after 1m0s
[21:20:05.908] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:67:0)
[21:20:05.908] Timer tick, asking Buildbucket for the build status
[21:20:06.036] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:06.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:68:0) after 1m0s
[21:21:06.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:68:0)
[21:21:06.189] Timer tick, asking Buildbucket for the build status
[21:21:06.334] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:06.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:69:0) after 1m0s
[21:22:06.364] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:69:0)
[21:22:06.364] Timer tick, asking Buildbucket for the build status
[21:22:06.423] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:06.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:70:0) after 1m0s
[21:23:06.447] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:70:0)
[21:23:06.447] Timer tick, asking Buildbucket for the build status
[21:23:06.543] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:06.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:71:0) after 1m0s
[21:24:06.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:71:0)
[21:24:06.602] Timer tick, asking Buildbucket for the build status
[21:24:06.669] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:06.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:72:0) after 1m0s
[21:25:06.694] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:72:0)
[21:25:06.694] Timer tick, asking Buildbucket for the build status
[21:25:07.089] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:07.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:73:0) after 1m0s
[21:26:07.162] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:73:0)
[21:26:07.162] Timer tick, asking Buildbucket for the build status
[21:26:07.232] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:07.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:74:0) after 1m0s
[21:27:07.287] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:74:0)
[21:27:07.287] Timer tick, asking Buildbucket for the build status
[21:27:08.185] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:08.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:75:0) after 1m0s
[21:28:08.243] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:75:0)
[21:28:08.243] Timer tick, asking Buildbucket for the build status
[21:28:08.307] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:08.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:76:0) after 1m0s
[21:29:08.455] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:76:0)
[21:29:08.455] Timer tick, asking Buildbucket for the build status
[21:29:08.564] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:08.564] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:77:0) after 1m0s
[21:30:08.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:77:0)
[21:30:08.612] Timer tick, asking Buildbucket for the build status
[21:30:08.902] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:08.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:78:0) after 1m0s
[21:31:08.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:78:0)
[21:31:08.978] Timer tick, asking Buildbucket for the build status
[21:31:09.323] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:09.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:79:0) after 1m0s
[21:32:09.414] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:79:0)
[21:32:09.414] Timer tick, asking Buildbucket for the build status
[21:32:09.477] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:09.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:80:0) after 1m0s
[21:33:09.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:80:0)
[21:33:09.490] Timer tick, asking Buildbucket for the build status
[21:33:09.573] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:09.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:81:0) after 1m0s
[21:34:09.700] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:81:0)
[21:34:09.700] Timer tick, asking Buildbucket for the build status
[21:34:09.782] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:09.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:82:0) after 1m0s
[21:35:09.831] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:82:0)
[21:35:09.831] Timer tick, asking Buildbucket for the build status
[21:35:09.918] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:09.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:83:0) after 1m0s
[21:36:09.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:83:0)
[21:36:09.974] Timer tick, asking Buildbucket for the build status
[21:36:10.056] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:10.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:84:0) after 1m0s
[21:37:10.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:84:0)
[21:37:10.126] Timer tick, asking Buildbucket for the build status
[21:37:10.197] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:10.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:85:0) after 1m0s
[21:38:10.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:85:0)
[21:38:10.272] Timer tick, asking Buildbucket for the build status
[21:38:10.366] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:10.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:86:0) after 1m0s
[21:39:10.418] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:86:0)
[21:39:10.418] Timer tick, asking Buildbucket for the build status
[21:39:10.511] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:10.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:87:0) after 1m0s
[21:40:10.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:87:0)
[21:40:10.695] Timer tick, asking Buildbucket for the build status
[21:40:10.930] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:10.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:88:0) after 1m0s
[21:41:11.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:88:0)
[21:41:11.628] Timer tick, asking Buildbucket for the build status
[21:41:12.007] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:12.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:89:0) after 1m0s
[21:42:12.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:89:0)
[21:42:12.035] Timer tick, asking Buildbucket for the build status
[21:42:12.096] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:12.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:90:0) after 1m0s
[21:43:12.388] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:90:0)
[21:43:12.388] Timer tick, asking Buildbucket for the build status
[21:43:12.446] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:12.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:91:0) after 1m0s
[21:44:12.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:91:0)
[21:44:12.545] Timer tick, asking Buildbucket for the build status
[21:44:12.602] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:12.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:92:0) after 1m0s
[21:45:12.667] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:92:0)
[21:45:12.667] Timer tick, asking Buildbucket for the build status
[21:45:12.778] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:12.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:93:0) after 1m0s
[21:46:12.766] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:93:0)
[21:46:12.766] Timer tick, asking Buildbucket for the build status
[21:46:12.840] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:12.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:94:0) after 1m0s
[21:47:12.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:94:0)
[21:47:12.913] Timer tick, asking Buildbucket for the build status
[21:47:12.975] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:12.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:95:0) after 1m0s
[21:48:12.963] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:95:0)
[21:48:12.963] Timer tick, asking Buildbucket for the build status
[21:48:13.268] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:13.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:96:0) after 1m0s
[21:49:13.299] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:96:0)
[21:49:13.299] Timer tick, asking Buildbucket for the build status
[21:49:13.377] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:13.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:97:0) after 1m0s
[21:50:13.428] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:97:0)
[21:50:13.429] Timer tick, asking Buildbucket for the build status
[21:50:13.577] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:13.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:98:0) after 1m0s
[21:51:13.655] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:98:0)
[21:51:13.655] Timer tick, asking Buildbucket for the build status
[21:51:13.723] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:13.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:99:0) after 1m0s
[21:52:13.727] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:99:0)
[21:52:13.727] Timer tick, asking Buildbucket for the build status
[21:52:13.791] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:13.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:100:0) after 1m0s
[21:53:13.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:100:0)
[21:53:13.814] Timer tick, asking Buildbucket for the build status
[21:53:13.901] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:13.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:101:0) after 1m0s
[21:54:13.927] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:101:0)
[21:54:13.927] Timer tick, asking Buildbucket for the build status
[21:54:13.989] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:13.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:102:0) after 1m0s
[21:55:14.023] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:102:0)
[21:55:14.023] Timer tick, asking Buildbucket for the build status
[21:55:14.279] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:14.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:103:0) after 1m0s
[21:56:14.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:103:0)
[21:56:14.312] Timer tick, asking Buildbucket for the build status
[21:56:14.385] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:14.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:104:0) after 1m0s
[21:57:14.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:104:0)
[21:57:14.510] Timer tick, asking Buildbucket for the build status
[21:57:14.572] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:14.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:105:0) after 1m0s
[21:58:14.628] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:105:0)
[21:58:14.628] Timer tick, asking Buildbucket for the build status
[21:58:14.687] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:14.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:106:0) after 1m0s
[21:59:14.712] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:106:0)
[21:59:14.712] Timer tick, asking Buildbucket for the build status
[21:59:14.968] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:14.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:107:0) after 1m0s
[22:00:15.211] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:107:0)
[22:00:15.211] Timer tick, asking Buildbucket for the build status
[22:00:15.680] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:15.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:108:0) after 1m0s
[22:01:15.731] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:108:0)
[22:01:15.731] Timer tick, asking Buildbucket for the build status
[22:01:15.796] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:15.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:109:0) after 1m0s
[22:02:16.035] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:109:0)
[22:02:16.035] Timer tick, asking Buildbucket for the build status
[22:02:16.094] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:16.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:110:0) after 1m0s
[22:03:16.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:110:0)
[22:03:16.142] Timer tick, asking Buildbucket for the build status
[22:03:16.297] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:16.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:111:0) after 1m0s
[22:04:16.326] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:111:0)
[22:04:16.326] Timer tick, asking Buildbucket for the build status
[22:04:16.392] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:16.392] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:112:0) after 1m0s
[22:05:16.567] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:112:0)
[22:05:16.567] Timer tick, asking Buildbucket for the build status
[22:05:16.820] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:16.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:113:0) after 1m0s
[22:06:16.837] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:113:0)
[22:06:16.837] Timer tick, asking Buildbucket for the build status
[22:06:17.297] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:17.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:114:0) after 1m0s
[22:07:17.481] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:114:0)
[22:07:17.481] Timer tick, asking Buildbucket for the build status
[22:07:17.546] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:17.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:115:0) after 1m0s
[22:08:17.578] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:115:0)
[22:08:17.579] Timer tick, asking Buildbucket for the build status
[22:08:17.654] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:17.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:116:0) after 1m0s
[22:09:17.756] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:116:0)
[22:09:17.756] Timer tick, asking Buildbucket for the build status
[22:09:17.864] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:17.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:117:0) after 1m0s
[22:10:18.043] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:117:0)
[22:10:18.043] Timer tick, asking Buildbucket for the build status
[22:10:18.391] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:18.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:118:0) after 1m0s
[22:11:18.450] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:118:0)
[22:11:18.450] Timer tick, asking Buildbucket for the build status
[22:11:18.511] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:18.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:119:0) after 1m0s
[22:12:18.511] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:119:0)
[22:12:18.511] Timer tick, asking Buildbucket for the build status
[22:12:18.579] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:18.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:120:0) after 1m0s
[22:13:18.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:120:0)
[22:13:18.678] Timer tick, asking Buildbucket for the build status
[22:13:18.987] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:18.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:121:0) after 1m0s
[22:14:19.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:121:0)
[22:14:19.105] Timer tick, asking Buildbucket for the build status
[22:14:19.163] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:19.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:122:0) after 1m0s
[22:15:19.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:122:0)
[22:15:19.201] Timer tick, asking Buildbucket for the build status
[22:15:19.298] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:19.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:123:0) after 1m0s
[22:16:19.312] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:123:0)
[22:16:19.312] Timer tick, asking Buildbucket for the build status
[22:16:19.368] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:19.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:124:0) after 1m0s
[22:17:19.394] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:124:0)
[22:17:19.394] Timer tick, asking Buildbucket for the build status
[22:17:19.462] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:19.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:125:0) after 1m0s
[22:18:19.695] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:125:0)
[22:18:19.695] Timer tick, asking Buildbucket for the build status
[22:18:19.753] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:19.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:126:0) after 1m0s
[22:19:20.064] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:126:0)
[22:19:20.064] Timer tick, asking Buildbucket for the build status
[22:19:20.169] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:20.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:127:0) after 1m0s
[22:20:20.238] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:127:0)
[22:20:20.238] Timer tick, asking Buildbucket for the build status
[22:20:20.375] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:20.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:128:0) after 1m0s
[22:21:20.452] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:128:0)
[22:21:20.452] Timer tick, asking Buildbucket for the build status
[22:21:20.744] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:20.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:129:0) after 1m0s
[22:22:20.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:129:0)
[22:22:20.768] Timer tick, asking Buildbucket for the build status
[22:22:20.829] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:20.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:130:0) after 1m0s
[22:23:20.852] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:130:0)
[22:23:20.852] Timer tick, asking Buildbucket for the build status
[22:23:20.924] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:20.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:131:0) after 1m0s
[22:24:20.966] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:131:0)
[22:24:20.966] Timer tick, asking Buildbucket for the build status
[22:24:21.056] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:21.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:132:0) after 1m0s
[22:25:21.207] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:132:0)
[22:25:21.207] Timer tick, asking Buildbucket for the build status
[22:25:21.416] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:21.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:133:0) after 1m0s
[22:26:21.588] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:133:0)
[22:26:21.588] Timer tick, asking Buildbucket for the build status
[22:26:21.908] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:21.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:134:0) after 1m0s
[22:27:21.938] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:134:0)
[22:27:21.938] Timer tick, asking Buildbucket for the build status
[22:27:22.553] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:22.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:135:0) after 1m0s
[22:28:22.758] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:135:0)
[22:28:22.758] Timer tick, asking Buildbucket for the build status
[22:28:22.977] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:22.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:136:0) after 1m0s
[22:29:23.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:136:0)
[22:29:23.075] Timer tick, asking Buildbucket for the build status
[22:29:23.153] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:23.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:137:0) after 1m0s
[22:30:23.256] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:137:0)
[22:30:23.256] Timer tick, asking Buildbucket for the build status
[22:30:23.559] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:23.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:138:0) after 1m0s
[22:31:23.611] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:138:0)
[22:31:23.611] Timer tick, asking Buildbucket for the build status
[22:31:23.678] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:23.678] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:139:0) after 1m0s
[22:32:23.732] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:139:0)
[22:32:23.732] Timer tick, asking Buildbucket for the build status
[22:32:23.790] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:23.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:140:0) after 1m0s
[22:33:23.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:140:0)
[22:33:23.839] Timer tick, asking Buildbucket for the build status
[22:33:23.895] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:23.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:141:0) after 1m0s
[22:34:23.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:141:0)
[22:34:23.894] Timer tick, asking Buildbucket for the build status
[22:34:23.948] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:23.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:142:0) after 1m0s
[22:35:23.974] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:142:0)
[22:35:23.974] Timer tick, asking Buildbucket for the build status
[22:35:24.419] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:24.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:143:0) after 1m0s
[22:36:24.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:143:0)
[22:36:24.451] Timer tick, asking Buildbucket for the build status
[22:36:24.510] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:24.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:144:0) after 1m0s
[22:37:24.591] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:144:0)
[22:37:24.591] Timer tick, asking Buildbucket for the build status
[22:37:24.656] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:24.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:145:0) after 1m0s
[22:38:24.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:145:0)
[22:38:24.745] Timer tick, asking Buildbucket for the build status
[22:38:24.818] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:24.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:146:0) after 1m0s
[22:39:24.917] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:146:0)
[22:39:24.917] Timer tick, asking Buildbucket for the build status
[22:39:25.002] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:25.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:147:0) after 1m0s
[22:40:25.053] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:147:0)
[22:40:25.053] Timer tick, asking Buildbucket for the build status
[22:40:25.602] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:25.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:148:0) after 1m0s
[22:41:25.843] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:148:0)
[22:41:25.843] Timer tick, asking Buildbucket for the build status
[22:41:25.895] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:41:25.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:149:0) after 1m0s
[22:42:25.958] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:149:0)
[22:42:25.958] Timer tick, asking Buildbucket for the build status
[22:42:26.436] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:42:26.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:150:0) after 1m0s
[22:43:26.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:150:0)
[22:43:26.501] Timer tick, asking Buildbucket for the build status
[22:43:26.566] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:43:26.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:151:0) after 1m0s
[22:44:26.990] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:151:0)
[22:44:26.990] Timer tick, asking Buildbucket for the build status
[22:44:27.043] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:44:27.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:152:0) after 1m0s
[22:45:27.098] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:152:0)
[22:45:27.098] Timer tick, asking Buildbucket for the build status
[22:45:27.213] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:45:27.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:153:0) after 1m0s
[22:46:27.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:153:0)
[22:46:27.263] Timer tick, asking Buildbucket for the build status
[22:46:27.344] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:46:27.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:154:0) after 1m0s
[22:47:27.496] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:154:0)
[22:47:27.496] Timer tick, asking Buildbucket for the build status
[22:47:27.584] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:47:27.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:155:0) after 1m0s
[22:48:27.755] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:155:0)
[22:48:27.755] Timer tick, asking Buildbucket for the build status
[22:48:27.823] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:27.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:156:0) after 1m0s
[22:49:27.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:156:0)
[22:49:27.867] Timer tick, asking Buildbucket for the build status
[22:49:27.923] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:27.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:157:0) after 1m0s
[22:50:27.975] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:157:0)
[22:50:27.975] Timer tick, asking Buildbucket for the build status
[22:50:28.090] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:28.090] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:158:0) after 1m0s
[22:51:28.115] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:158:0)
[22:51:28.115] Timer tick, asking Buildbucket for the build status
[22:51:28.185] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:28.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:159:0) after 1m0s
[22:52:28.189] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:159:0)
[22:52:28.189] Timer tick, asking Buildbucket for the build status
[22:52:28.240] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:28.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:160:0) after 1m0s
[22:53:28.275] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:160:0)
[22:53:28.275] Timer tick, asking Buildbucket for the build status
[22:53:28.331] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:28.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:161:0) after 1m0s
[22:54:28.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:161:0)
[22:54:28.400] Timer tick, asking Buildbucket for the build status
[22:54:28.798] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:28.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:162:0) after 1m0s
[22:55:28.881] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:162:0)
[22:55:28.881] Timer tick, asking Buildbucket for the build status
[22:55:29.007] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:29.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:163:0) after 1m0s
[22:56:29.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:163:0)
[22:56:29.038] Timer tick, asking Buildbucket for the build status
[22:56:29.108] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:29.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:164:0) after 1m0s
[22:57:29.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:164:0)
[22:57:29.146] Timer tick, asking Buildbucket for the build status
[22:57:29.213] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:29.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:165:0) after 1m0s
[22:58:29.264] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:165:0)
[22:58:29.264] Timer tick, asking Buildbucket for the build status
[22:58:29.331] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:29.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:166:0) after 1m0s
[22:59:29.362] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:166:0)
[22:59:29.362] Timer tick, asking Buildbucket for the build status
[22:59:29.446] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:29.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:167:0) after 1m0s
[23:00:29.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:167:0)
[23:00:29.545] Timer tick, asking Buildbucket for the build status
[23:00:29.700] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:29.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:168:0) after 1m0s
[23:01:29.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:168:0)
[23:01:29.768] Timer tick, asking Buildbucket for the build status
[23:01:30.383] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:30.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:169:0) after 1m0s
[23:02:30.423] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:169:0)
[23:02:30.423] Timer tick, asking Buildbucket for the build status
[23:02:30.600] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:30.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:170:0) after 1m0s
[23:03:30.635] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:170:0)
[23:03:30.635] Timer tick, asking Buildbucket for the build status
[23:03:31.148] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:31.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:171:0) after 1m0s
[23:04:31.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:171:0)
[23:04:31.178] Timer tick, asking Buildbucket for the build status
[23:04:31.260] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:31.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:172:0) after 1m0s
[23:05:31.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:172:0)
[23:05:31.290] Timer tick, asking Buildbucket for the build status
[23:05:31.417] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:31.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:173:0) after 1m0s
[23:06:31.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:173:0)
[23:06:31.470] Timer tick, asking Buildbucket for the build status
[23:06:31.571] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:31.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:174:0) after 1m0s
[23:07:31.601] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:174:0)
[23:07:31.601] Timer tick, asking Buildbucket for the build status
[23:07:31.661] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:31.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:175:0) after 1m0s
[23:08:31.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:175:0)
[23:08:31.730] Timer tick, asking Buildbucket for the build status
[23:08:34.639] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:34.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:176:0) after 1m0s
[23:09:34.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:176:0)
[23:09:34.678] Timer tick, asking Buildbucket for the build status
[23:09:34.744] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:34.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:177:0) after 1m0s
[23:10:34.802] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:177:0)
[23:10:34.802] Timer tick, asking Buildbucket for the build status
[23:10:34.967] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:34.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:178:0) after 1m0s
[23:11:35.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:178:0)
[23:11:35.033] Timer tick, asking Buildbucket for the build status
[23:11:35.135] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:35.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:179:0) after 1m0s
[23:12:35.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:179:0)
[23:12:35.126] Timer tick, asking Buildbucket for the build status
[23:12:35.236] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:35.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:180:0) after 1m0s
[23:13:35.285] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:180:0)
[23:13:35.285] Timer tick, asking Buildbucket for the build status
[23:13:35.343] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:35.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:181:0) after 1m0s
[23:14:35.384] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:181:0)
[23:14:35.384] Timer tick, asking Buildbucket for the build status
[23:14:35.448] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:35.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:182:0) after 1m0s
[23:15:35.510] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:182:0)
[23:15:35.510] Timer tick, asking Buildbucket for the build status
[23:15:35.664] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:35.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:183:0) after 1m0s
[23:16:35.741] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:183:0)
[23:16:35.741] Timer tick, asking Buildbucket for the build status
[23:16:35.901] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:35.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:184:0) after 1m0s
[23:17:35.988] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:184:0)
[23:17:35.988] Timer tick, asking Buildbucket for the build status
[23:17:36.051] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:36.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:185:0) after 1m0s
[23:18:36.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:185:0)
[23:18:36.196] Timer tick, asking Buildbucket for the build status
[23:18:36.282] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:36.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:186:0) after 1m0s
[23:19:36.545] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:186:0)
[23:19:36.545] Timer tick, asking Buildbucket for the build status
[23:19:36.659] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:36.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:187:0) after 1m0s
[23:20:36.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:187:0)
[23:20:36.725] Timer tick, asking Buildbucket for the build status
[23:20:37.453] Build 8860907743124088480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:37.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9026337805122724848:188:0) after 1m0s
[23:21:34.491] Received PubSub notification, asking Buildbucket for the build status
[23:21:34.649] Build 8860907743124088480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:21:34.649] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607988093567954",
"created_by": "project:chromium",
"created_ts": "1607976887545739",
"id": "8860907743124088480",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0249ee0643009646e3d59cf0d7d6ec674a16b2d2\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\", \"use_luci_auth\": true}, \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"0249ee0643009646e3d59cf0d7d6ec674a16b2d2\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-6-xk7d\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win-official\", \"buildnumber\": 1169, \"got_angle_revision\": \"3df29be284458dde76dc1b1e30793d682f9a60ff\", \"got_dawn_revision\": \"12c78cbc820a52c4ca2da8e92d9faf815d9b3e93\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"0249ee0643009646e3d59cf0d7d6ec674a16b2d2\", \"got_revision_cp\": \"refs/heads/master@{#836737}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"c16dc7107fd329b815c700b002d1ba40372b16dc\", \"got_v8_revision\": \"afdda12d2c531c8d2064d5f9062d2c0c23f63e33\", \"got_v8_revision_cp\": \"refs/heads/8.9.145@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"mirrored_builders\": [\"tryserver.chromium:win-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0249ee0643009646e3d59cf0d7d6ec674a16b2d2\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-6-xk7d\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"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": "1607976925961380",
"status": "COMPLETED",
"status_changed_ts": "1607988094039338",
"tags": [
"build_address:luci.chromium.ci/win-official/1169",
"builder:win-official",
"buildset:commit/git/0249ee0643009646e3d59cf0d7d6ec674a16b2d2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0249ee0643009646e3d59cf0d7d6ec674a16b2d2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026337805122724848",
"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/8860907743124088480/+/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:507bb5b46057a110",
"user_agent:luci-scheduler"
],
"updated_ts": "1607988094045206",
"url": "https://ci.chromium.org/b/8860907743124088480",
"utcnow_ts": "1607988094638546"
}
}
[23:21:34.649] Invocation finished in 3h6m48.394425004s with status SUCCEEDED