[13:10:12.234] New invocation is queued and will start shortly
[13:10:16.223] Starting the invocation (attempt 1)
[13:10:16.269] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:10:16.269] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:10:16.269] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022197056136728080",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b72cf882000dfab1ab01faac718ef98904b3f639\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9022197056136728080",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/b72cf882000dfab1ab01faac718ef98904b3f639",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b72cf882000dfab1ab01faac718ef98904b3f639",
"gitiles_ref:refs/heads/master"
]
}
[13:10:17.049] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611925816369434",
"id": "8856766991133248032",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b72cf882000dfab1ab01faac718ef98904b3f639\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611925816831124",
"tags": [
"build_address:luci.chromium.ci/win32-official/1440",
"builder:win32-official",
"buildset:commit/git/b72cf882000dfab1ab01faac718ef98904b3f639",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b72cf882000dfab1ab01faac718ef98904b3f639",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022197056136728080",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856766991133248032/+/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": "1611925816831409",
"url": "https://ci.chromium.org/b/8856766991133248032",
"utcnow_ts": "1611925817031644"
}
}
[13:10:17.049] Task URL: https://ci.chromium.org/b/8856766991133248032
[13:10:17.049] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:2:0) after 1m0s
[13:10:28.600] Received PubSub notification, asking Buildbucket for the build status
[13:10:28.813] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:17.125] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:2:0)
[13:11:17.125] Timer tick, asking Buildbucket for the build status
[13:11:17.208] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:17.208] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:4:0) after 1m0s
[13:12:17.285] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:4:0)
[13:12:17.285] Timer tick, asking Buildbucket for the build status
[13:12:17.428] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:17.428] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:5:0) after 1m0s
[13:13:17.528] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:5:0)
[13:13:17.528] Timer tick, asking Buildbucket for the build status
[13:13:17.826] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:17.826] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:6:0) after 1m0s
[13:14:17.899] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:6:0)
[13:14:17.899] Timer tick, asking Buildbucket for the build status
[13:14:17.973] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:17.974] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:7:0) after 1m0s
[13:15:18.203] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:7:0)
[13:15:18.203] Timer tick, asking Buildbucket for the build status
[13:15:18.444] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:18.444] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:8:0) after 1m0s
[13:16:18.566] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:8:0)
[13:16:18.566] Timer tick, asking Buildbucket for the build status
[13:16:18.704] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:18.704] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:9:0) after 1m0s
[13:17:18.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:9:0)
[13:17:18.756] Timer tick, asking Buildbucket for the build status
[13:17:18.815] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:18.815] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:10:0) after 1m0s
[13:18:19.096] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:10:0)
[13:18:19.097] Timer tick, asking Buildbucket for the build status
[13:18:19.232] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:19.232] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:11:0) after 1m0s
[13:19:19.351] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:11:0)
[13:19:19.351] Timer tick, asking Buildbucket for the build status
[13:19:19.430] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:19.430] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:12:0) after 1m0s
[13:20:19.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:12:0)
[13:20:19.574] Timer tick, asking Buildbucket for the build status
[13:20:19.654] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:19.654] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:13:0) after 1m0s
[13:21:19.811] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:13:0)
[13:21:19.811] Timer tick, asking Buildbucket for the build status
[13:21:19.881] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:19.881] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:14:0) after 1m0s
[13:22:20.008] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:14:0)
[13:22:20.008] Timer tick, asking Buildbucket for the build status
[13:22:20.216] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:20.216] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:15:0) after 1m0s
[13:23:20.333] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:15:0)
[13:23:20.333] Timer tick, asking Buildbucket for the build status
[13:23:20.701] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:20.701] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:16:0) after 1m0s
[13:24:20.831] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:16:0)
[13:24:20.831] Timer tick, asking Buildbucket for the build status
[13:24:21.058] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:21.059] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:17:0) after 1m0s
[13:25:21.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:17:0)
[13:25:21.156] Timer tick, asking Buildbucket for the build status
[13:25:21.301] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:21.301] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:18:0) after 1m0s
[13:26:21.332] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:18:0)
[13:26:21.332] Timer tick, asking Buildbucket for the build status
[13:26:21.697] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:21.697] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:19:0) after 1m0s
[13:27:21.830] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:19:0)
[13:27:21.830] Timer tick, asking Buildbucket for the build status
[13:27:21.887] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:21.887] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:20:0) after 1m0s
[13:28:21.956] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:20:0)
[13:28:21.956] Timer tick, asking Buildbucket for the build status
[13:28:22.046] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:22.046] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:21:0) after 1m0s
[13:29:22.115] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:21:0)
[13:29:22.115] Timer tick, asking Buildbucket for the build status
[13:29:22.164] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:22.164] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:22:0) after 1m0s
[13:30:22.203] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:22:0)
[13:30:22.203] Timer tick, asking Buildbucket for the build status
[13:30:22.804] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:22.804] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:23:0) after 1m0s
[13:31:22.879] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:23:0)
[13:31:22.880] Timer tick, asking Buildbucket for the build status
[13:31:22.959] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:22.960] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:24:0) after 1m0s
[13:32:22.992] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:24:0)
[13:32:22.993] Timer tick, asking Buildbucket for the build status
[13:32:23.051] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:23.051] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:25:0) after 1m0s
[13:33:23.203] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:25:0)
[13:33:23.203] Timer tick, asking Buildbucket for the build status
[13:33:23.414] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:23.414] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:26:0) after 1m0s
[13:34:23.583] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:26:0)
[13:34:23.583] Timer tick, asking Buildbucket for the build status
[13:34:23.858] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:23.858] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:27:0) after 1m0s
[13:35:23.908] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:27:0)
[13:35:23.908] Timer tick, asking Buildbucket for the build status
[13:35:24.396] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:24.396] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:28:0) after 1m0s
[13:36:24.464] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:28:0)
[13:36:24.464] Timer tick, asking Buildbucket for the build status
[13:36:24.563] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:24.563] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:29:0) after 1m0s
[13:37:24.653] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:29:0)
[13:37:24.653] Timer tick, asking Buildbucket for the build status
[13:37:24.787] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:24.787] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:30:0) after 1m0s
[13:38:24.813] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:30:0)
[13:38:24.813] Timer tick, asking Buildbucket for the build status
[13:38:24.923] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:24.923] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:31:0) after 1m0s
[13:39:24.979] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:31:0)
[13:39:24.979] Timer tick, asking Buildbucket for the build status
[13:39:25.091] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:25.091] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:32:0) after 1m0s
[13:40:25.121] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:32:0)
[13:40:25.121] Timer tick, asking Buildbucket for the build status
[13:40:25.240] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:25.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:33:0) after 1m0s
[13:41:25.326] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:33:0)
[13:41:25.326] Timer tick, asking Buildbucket for the build status
[13:41:25.432] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:25.432] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:34:0) after 1m0s
[13:42:25.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:34:0)
[13:42:25.573] Timer tick, asking Buildbucket for the build status
[13:42:25.689] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:25.689] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:35:0) after 1m0s
[13:43:25.771] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:35:0)
[13:43:25.771] Timer tick, asking Buildbucket for the build status
[13:43:25.839] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:25.839] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:36:0) after 1m0s
[13:44:25.847] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:36:0)
[13:44:25.847] Timer tick, asking Buildbucket for the build status
[13:44:25.919] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:25.919] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:37:0) after 1m0s
[13:45:26.087] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:37:0)
[13:45:26.087] Timer tick, asking Buildbucket for the build status
[13:45:26.218] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:26.218] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:38:0) after 1m0s
[13:46:26.301] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:38:0)
[13:46:26.301] Timer tick, asking Buildbucket for the build status
[13:46:26.387] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:26.387] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:39:0) after 1m0s
[13:47:26.537] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:39:0)
[13:47:26.537] Timer tick, asking Buildbucket for the build status
[13:47:26.600] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:26.600] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:40:0) after 1m0s
[13:48:26.654] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:40:0)
[13:48:26.654] Timer tick, asking Buildbucket for the build status
[13:48:26.771] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:26.771] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:41:0) after 1m0s
[13:49:26.750] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:41:0)
[13:49:26.750] Timer tick, asking Buildbucket for the build status
[13:49:26.818] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:26.818] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:42:0) after 1m0s
[13:50:26.845] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:42:0)
[13:50:26.845] Timer tick, asking Buildbucket for the build status
[13:50:26.951] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:26.951] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:43:0) after 1m0s
[13:51:26.992] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:43:0)
[13:51:26.992] Timer tick, asking Buildbucket for the build status
[13:51:27.055] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:27.055] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:44:0) after 1m0s
[13:52:27.058] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:44:0)
[13:52:27.058] Timer tick, asking Buildbucket for the build status
[13:52:27.209] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:27.209] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:45:0) after 1m0s
[13:53:27.231] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:45:0)
[13:53:27.231] Timer tick, asking Buildbucket for the build status
[13:53:27.283] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:27.283] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:46:0) after 1m0s
[13:54:27.385] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:46:0)
[13:54:27.385] Timer tick, asking Buildbucket for the build status
[13:54:27.514] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:27.514] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:47:0) after 1m0s
[13:55:27.572] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:47:0)
[13:55:27.572] Timer tick, asking Buildbucket for the build status
[13:55:27.835] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:27.835] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:48:0) after 1m0s
[13:56:27.949] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:48:0)
[13:56:27.949] Timer tick, asking Buildbucket for the build status
[13:56:28.015] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:28.015] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:49:0) after 1m0s
[13:57:28.065] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:49:0)
[13:57:28.065] Timer tick, asking Buildbucket for the build status
[13:57:28.122] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:28.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:50:0) after 1m0s
[13:58:28.196] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:50:0)
[13:58:28.196] Timer tick, asking Buildbucket for the build status
[13:58:28.253] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:28.253] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:51:0) after 1m0s
[13:59:28.286] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:51:0)
[13:59:28.286] Timer tick, asking Buildbucket for the build status
[13:59:28.340] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:28.340] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:52:0) after 1m0s
[14:00:28.457] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:52:0)
[14:00:28.457] Timer tick, asking Buildbucket for the build status
[14:00:28.569] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:28.569] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:53:0) after 1m0s
[14:01:28.596] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:53:0)
[14:01:28.596] Timer tick, asking Buildbucket for the build status
[14:01:28.651] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:28.651] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:54:0) after 1m0s
[14:02:28.685] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:54:0)
[14:02:28.685] Timer tick, asking Buildbucket for the build status
[14:02:28.935] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:28.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:55:0) after 1m0s
[14:03:28.954] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:55:0)
[14:03:28.954] Timer tick, asking Buildbucket for the build status
[14:03:29.013] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:29.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:56:0) after 1m0s
[14:04:29.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:56:0)
[14:04:29.069] Timer tick, asking Buildbucket for the build status
[14:04:29.125] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:29.125] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:57:0) after 1m0s
[14:05:29.137] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:57:0)
[14:05:29.137] Timer tick, asking Buildbucket for the build status
[14:05:29.265] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:29.265] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:58:0) after 1m0s
[14:06:29.359] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:58:0)
[14:06:29.359] Timer tick, asking Buildbucket for the build status
[14:06:29.419] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:29.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:59:0) after 1m0s
[14:07:29.596] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:59:0)
[14:07:29.596] Timer tick, asking Buildbucket for the build status
[14:07:29.796] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:29.796] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:60:0) after 1m0s
[14:08:29.838] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:60:0)
[14:08:29.838] Timer tick, asking Buildbucket for the build status
[14:08:29.897] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:29.897] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:61:0) after 1m0s
[14:09:29.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:61:0)
[14:09:29.969] Timer tick, asking Buildbucket for the build status
[14:09:30.033] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:30.033] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:62:0) after 1m0s
[14:10:30.071] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:62:0)
[14:10:30.071] Timer tick, asking Buildbucket for the build status
[14:10:30.179] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:30.179] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:63:0) after 1m0s
[14:11:30.351] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:63:0)
[14:11:30.351] Timer tick, asking Buildbucket for the build status
[14:11:30.469] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:30.469] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:64:0) after 1m0s
[14:12:30.563] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:64:0)
[14:12:30.563] Timer tick, asking Buildbucket for the build status
[14:12:30.610] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:30.610] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:65:0) after 1m0s
[14:13:30.765] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:65:0)
[14:13:30.765] Timer tick, asking Buildbucket for the build status
[14:13:30.828] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:30.828] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:66:0) after 1m0s
[14:14:30.890] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:66:0)
[14:14:30.890] Timer tick, asking Buildbucket for the build status
[14:14:30.939] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:30.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:67:0) after 1m0s
[14:15:30.973] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:67:0)
[14:15:30.973] Timer tick, asking Buildbucket for the build status
[14:15:31.124] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:31.124] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:68:0) after 1m0s
[14:16:31.249] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:68:0)
[14:16:31.249] Timer tick, asking Buildbucket for the build status
[14:16:31.317] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:31.317] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:69:0) after 1m0s
[14:17:31.338] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:69:0)
[14:17:31.338] Timer tick, asking Buildbucket for the build status
[14:17:31.605] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:31.605] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:70:0) after 1m0s
[14:18:31.616] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:70:0)
[14:18:31.616] Timer tick, asking Buildbucket for the build status
[14:18:31.794] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:31.794] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:71:0) after 1m0s
[14:19:31.960] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:71:0)
[14:19:31.960] Timer tick, asking Buildbucket for the build status
[14:19:32.043] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:32.044] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:72:0) after 1m0s
[14:20:32.110] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:72:0)
[14:20:32.110] Timer tick, asking Buildbucket for the build status
[14:20:32.539] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:32.539] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:73:0) after 1m0s
[14:21:32.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:73:0)
[14:21:32.574] Timer tick, asking Buildbucket for the build status
[14:21:32.668] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:32.668] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:74:0) after 1m0s
[14:22:32.756] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:74:0)
[14:22:32.756] Timer tick, asking Buildbucket for the build status
[14:22:32.860] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:32.860] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:75:0) after 1m0s
[14:23:33.022] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:75:0)
[14:23:33.022] Timer tick, asking Buildbucket for the build status
[14:23:33.265] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:33.265] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:76:0) after 1m0s
[14:24:33.326] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:76:0)
[14:24:33.326] Timer tick, asking Buildbucket for the build status
[14:24:33.390] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:33.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:77:0) after 1m0s
[14:25:33.372] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:77:0)
[14:25:33.372] Timer tick, asking Buildbucket for the build status
[14:25:33.834] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:33.834] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:78:0) after 1m0s
[14:26:33.824] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:78:0)
[14:26:33.824] Timer tick, asking Buildbucket for the build status
[14:26:33.889] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:33.890] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:79:0) after 1m0s
[14:27:33.998] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:79:0)
[14:27:33.998] Timer tick, asking Buildbucket for the build status
[14:27:34.105] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:34.105] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:80:0) after 1m0s
[14:28:34.127] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:80:0)
[14:28:34.127] Timer tick, asking Buildbucket for the build status
[14:28:34.297] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:34.297] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:81:0) after 1m0s
[14:29:34.412] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:81:0)
[14:29:34.412] Timer tick, asking Buildbucket for the build status
[14:29:34.510] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:34.510] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:82:0) after 1m0s
[14:30:34.593] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:82:0)
[14:30:34.593] Timer tick, asking Buildbucket for the build status
[14:30:34.733] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:34.733] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:83:0) after 1m0s
[14:31:34.772] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:83:0)
[14:31:34.772] Timer tick, asking Buildbucket for the build status
[14:31:34.935] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:34.935] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:84:0) after 1m0s
[14:32:35.322] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:84:0)
[14:32:35.322] Timer tick, asking Buildbucket for the build status
[14:32:35.521] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:35.521] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:85:0) after 1m0s
[14:33:35.592] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:85:0)
[14:33:35.592] Timer tick, asking Buildbucket for the build status
[14:33:35.736] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:35.736] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:86:0) after 1m0s
[14:34:35.788] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:86:0)
[14:34:35.788] Timer tick, asking Buildbucket for the build status
[14:34:35.955] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:35.955] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:87:0) after 1m0s
[14:35:35.996] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:87:0)
[14:35:35.996] Timer tick, asking Buildbucket for the build status
[14:35:36.089] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:36.089] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:88:0) after 1m0s
[14:36:36.328] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:88:0)
[14:36:36.328] Timer tick, asking Buildbucket for the build status
[14:36:36.561] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:36.561] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:89:0) after 1m0s
[14:37:36.633] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:89:0)
[14:37:36.633] Timer tick, asking Buildbucket for the build status
[14:37:36.740] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:36.740] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:90:0) after 1m0s
[14:38:36.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:90:0)
[14:38:36.798] Timer tick, asking Buildbucket for the build status
[14:38:36.875] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:36.875] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:91:0) after 1m0s
[14:39:37.108] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:91:0)
[14:39:37.108] Timer tick, asking Buildbucket for the build status
[14:39:37.170] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:37.170] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:92:0) after 1m0s
[14:40:37.329] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:92:0)
[14:40:37.329] Timer tick, asking Buildbucket for the build status
[14:40:37.612] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:37.612] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:93:0) after 1m0s
[14:41:37.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:93:0)
[14:41:37.690] Timer tick, asking Buildbucket for the build status
[14:41:37.761] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:37.761] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:94:0) after 1m0s
[14:42:37.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:94:0)
[14:42:37.892] Timer tick, asking Buildbucket for the build status
[14:42:37.977] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:37.977] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:95:0) after 1m0s
[14:43:38.075] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:95:0)
[14:43:38.075] Timer tick, asking Buildbucket for the build status
[14:43:38.242] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:38.242] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:96:0) after 1m0s
[14:44:38.283] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:96:0)
[14:44:38.283] Timer tick, asking Buildbucket for the build status
[14:44:38.357] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:38.357] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:97:0) after 1m0s
[14:45:38.400] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:97:0)
[14:45:38.400] Timer tick, asking Buildbucket for the build status
[14:45:38.733] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:38.733] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:98:0) after 1m0s
[14:46:39.067] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:98:0)
[14:46:39.067] Timer tick, asking Buildbucket for the build status
[14:46:39.333] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:39.333] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:99:0) after 1m0s
[14:47:39.429] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:99:0)
[14:47:39.429] Timer tick, asking Buildbucket for the build status
[14:47:39.511] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:39.511] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:100:0) after 1m0s
[14:48:39.641] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:100:0)
[14:48:39.641] Timer tick, asking Buildbucket for the build status
[14:48:39.710] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:39.710] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:101:0) after 1m0s
[14:49:39.826] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:101:0)
[14:49:39.826] Timer tick, asking Buildbucket for the build status
[14:49:40.231] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:40.231] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:102:0) after 1m0s
[14:50:40.404] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:102:0)
[14:50:40.404] Timer tick, asking Buildbucket for the build status
[14:50:40.740] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:40.740] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:103:0) after 1m0s
[14:51:40.826] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:103:0)
[14:51:40.826] Timer tick, asking Buildbucket for the build status
[14:51:41.122] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:41.122] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:104:0) after 1m0s
[14:52:41.178] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:104:0)
[14:52:41.178] Timer tick, asking Buildbucket for the build status
[14:52:41.343] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:41.343] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:105:0) after 1m0s
[14:53:41.483] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:105:0)
[14:53:41.483] Timer tick, asking Buildbucket for the build status
[14:53:41.629] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:41.629] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:106:0) after 1m0s
[14:54:41.808] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:106:0)
[14:54:41.808] Timer tick, asking Buildbucket for the build status
[14:54:42.124] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:42.124] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:107:0) after 1m0s
[14:55:42.291] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:107:0)
[14:55:42.291] Timer tick, asking Buildbucket for the build status
[14:55:42.460] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:42.460] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:108:0) after 1m0s
[14:56:42.620] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:108:0)
[14:56:42.620] Timer tick, asking Buildbucket for the build status
[14:56:42.685] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:42.685] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:109:0) after 1m0s
[14:57:42.721] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:109:0)
[14:57:42.721] Timer tick, asking Buildbucket for the build status
[14:57:42.931] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:42.931] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:110:0) after 1m0s
[14:58:43.044] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:110:0)
[14:58:43.044] Timer tick, asking Buildbucket for the build status
[14:58:43.217] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:43.217] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:111:0) after 1m0s
[14:59:43.377] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:111:0)
[14:59:43.377] Timer tick, asking Buildbucket for the build status
[14:59:43.447] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:112:0) after 1m0s
[15:00:43.490] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:112:0)
[15:00:43.490] Timer tick, asking Buildbucket for the build status
[15:00:45.139] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:45.139] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:113:0) after 1m0s
[15:01:45.201] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:113:0)
[15:01:45.201] Timer tick, asking Buildbucket for the build status
[15:01:45.277] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:45.278] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:114:0) after 1m0s
[15:02:45.797] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:114:0)
[15:02:45.797] Timer tick, asking Buildbucket for the build status
[15:02:45.983] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:45.983] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:115:0) after 1m0s
[15:03:46.105] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:115:0)
[15:03:46.105] Timer tick, asking Buildbucket for the build status
[15:03:46.336] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:46.337] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:116:0) after 1m0s
[15:04:46.398] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:116:0)
[15:04:46.398] Timer tick, asking Buildbucket for the build status
[15:04:46.473] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:46.473] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:117:0) after 1m0s
[15:05:46.730] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:117:0)
[15:05:46.731] Timer tick, asking Buildbucket for the build status
[15:05:46.892] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:46.892] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:118:0) after 1m0s
[15:06:46.971] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:118:0)
[15:06:46.971] Timer tick, asking Buildbucket for the build status
[15:06:47.032] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:47.032] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:119:0) after 1m0s
[15:07:47.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:119:0)
[15:07:47.016] Timer tick, asking Buildbucket for the build status
[15:07:47.076] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:47.076] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:120:0) after 1m0s
[15:08:47.123] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:120:0)
[15:08:47.123] Timer tick, asking Buildbucket for the build status
[15:08:47.183] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:47.183] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:121:0) after 1m0s
[15:09:47.220] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:121:0)
[15:09:47.220] Timer tick, asking Buildbucket for the build status
[15:09:47.328] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:47.328] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:122:0) after 1m0s
[15:10:47.386] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:122:0)
[15:10:47.386] Timer tick, asking Buildbucket for the build status
[15:10:47.641] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:47.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:123:0) after 1m0s
[15:11:47.728] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:123:0)
[15:11:47.728] Timer tick, asking Buildbucket for the build status
[15:11:47.921] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:47.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:124:0) after 1m0s
[15:12:47.997] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:124:0)
[15:12:47.997] Timer tick, asking Buildbucket for the build status
[15:12:48.072] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:48.072] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:125:0) after 1m0s
[15:13:48.187] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:125:0)
[15:13:48.188] Timer tick, asking Buildbucket for the build status
[15:13:48.557] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:48.557] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:126:0) after 1m0s
[15:14:48.613] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:126:0)
[15:14:48.613] Timer tick, asking Buildbucket for the build status
[15:14:48.681] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:48.681] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:127:0) after 1m0s
[15:15:48.800] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:127:0)
[15:15:48.800] Timer tick, asking Buildbucket for the build status
[15:15:48.975] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:48.975] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:128:0) after 1m0s
[15:16:49.019] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:128:0)
[15:16:49.019] Timer tick, asking Buildbucket for the build status
[15:16:49.098] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:49.098] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:129:0) after 1m0s
[15:17:49.220] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:129:0)
[15:17:49.220] Timer tick, asking Buildbucket for the build status
[15:17:49.273] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:49.273] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:130:0) after 1m0s
[15:18:49.735] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:130:0)
[15:18:49.735] Timer tick, asking Buildbucket for the build status
[15:18:49.877] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:49.877] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:131:0) after 1m0s
[15:19:50.069] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:131:0)
[15:19:50.069] Timer tick, asking Buildbucket for the build status
[15:19:50.136] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:50.136] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:132:0) after 1m0s
[15:20:50.375] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:132:0)
[15:20:50.375] Timer tick, asking Buildbucket for the build status
[15:20:50.471] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:50.471] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:133:0) after 1m0s
[15:21:50.768] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:133:0)
[15:21:50.768] Timer tick, asking Buildbucket for the build status
[15:21:50.851] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:50.851] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:134:0) after 1m0s
[15:22:51.064] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:134:0)
[15:22:51.064] Timer tick, asking Buildbucket for the build status
[15:22:51.146] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:51.146] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:135:0) after 1m0s
[15:23:51.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:135:0)
[15:23:51.344] Timer tick, asking Buildbucket for the build status
[15:23:51.503] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:51.503] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:136:0) after 1m0s
[15:24:51.640] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:136:0)
[15:24:51.640] Timer tick, asking Buildbucket for the build status
[15:24:51.732] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:51.733] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:137:0) after 1m0s
[15:25:51.886] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:137:0)
[15:25:51.886] Timer tick, asking Buildbucket for the build status
[15:25:52.144] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:52.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:138:0) after 1m0s
[15:26:52.182] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:138:0)
[15:26:52.182] Timer tick, asking Buildbucket for the build status
[15:26:52.296] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:52.296] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:139:0) after 1m0s
[15:27:52.337] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:139:0)
[15:27:52.337] Timer tick, asking Buildbucket for the build status
[15:27:52.397] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:52.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:140:0) after 1m0s
[15:28:52.500] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:140:0)
[15:28:52.500] Timer tick, asking Buildbucket for the build status
[15:28:52.565] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:52.565] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:141:0) after 1m0s
[15:29:52.635] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:141:0)
[15:29:52.635] Timer tick, asking Buildbucket for the build status
[15:29:52.701] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:52.701] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:142:0) after 1m0s
[15:30:52.829] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:142:0)
[15:30:52.829] Timer tick, asking Buildbucket for the build status
[15:30:53.023] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:53.023] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:143:0) after 1m0s
[15:31:53.145] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:143:0)
[15:31:53.145] Timer tick, asking Buildbucket for the build status
[15:31:53.220] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:53.220] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:144:0) after 1m0s
[15:32:53.408] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:144:0)
[15:32:53.408] Timer tick, asking Buildbucket for the build status
[15:32:53.498] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:53.498] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:145:0) after 1m0s
[15:33:53.542] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:145:0)
[15:33:53.542] Timer tick, asking Buildbucket for the build status
[15:33:53.602] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:53.602] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:146:0) after 1m0s
[15:34:53.625] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:146:0)
[15:34:53.625] Timer tick, asking Buildbucket for the build status
[15:34:53.690] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:53.690] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:147:0) after 1m0s
[15:35:53.804] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:147:0)
[15:35:53.804] Timer tick, asking Buildbucket for the build status
[15:35:54.053] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:54.053] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:148:0) after 1m0s
[15:36:54.118] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:148:0)
[15:36:54.118] Timer tick, asking Buildbucket for the build status
[15:36:54.225] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:54.225] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:149:0) after 1m0s
[15:37:54.382] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:149:0)
[15:37:54.382] Timer tick, asking Buildbucket for the build status
[15:37:54.562] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:54.562] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:150:0) after 1m0s
[15:38:54.607] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:150:0)
[15:38:54.607] Timer tick, asking Buildbucket for the build status
[15:38:54.670] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:54.670] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:151:0) after 1m0s
[15:39:54.671] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:151:0)
[15:39:54.671] Timer tick, asking Buildbucket for the build status
[15:39:54.833] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:54.833] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:152:0) after 1m0s
[15:40:54.868] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:152:0)
[15:40:54.868] Timer tick, asking Buildbucket for the build status
[15:40:54.963] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:54.963] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:153:0) after 1m0s
[15:41:54.951] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:153:0)
[15:41:54.951] Timer tick, asking Buildbucket for the build status
[15:41:55.023] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:55.023] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:154:0) after 1m0s
[15:42:55.056] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:154:0)
[15:42:55.056] Timer tick, asking Buildbucket for the build status
[15:42:55.109] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:55.109] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:155:0) after 1m0s
[15:43:55.228] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:155:0)
[15:43:55.228] Timer tick, asking Buildbucket for the build status
[15:43:55.375] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:55.375] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:156:0) after 1m0s
[15:44:55.404] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:156:0)
[15:44:55.404] Timer tick, asking Buildbucket for the build status
[15:44:55.473] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:55.473] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:157:0) after 1m0s
[15:45:55.541] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:157:0)
[15:45:55.541] Timer tick, asking Buildbucket for the build status
[15:45:56.011] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:56.011] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:158:0) after 1m0s
[15:46:56.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:158:0)
[15:46:56.131] Timer tick, asking Buildbucket for the build status
[15:46:56.221] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:56.221] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:159:0) after 1m0s
[15:47:56.508] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:159:0)
[15:47:56.508] Timer tick, asking Buildbucket for the build status
[15:47:56.576] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:56.576] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:160:0) after 1m0s
[15:48:56.655] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:160:0)
[15:48:56.655] Timer tick, asking Buildbucket for the build status
[15:48:56.720] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:56.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:161:0) after 1m0s
[15:49:56.993] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:161:0)
[15:49:56.993] Timer tick, asking Buildbucket for the build status
[15:49:57.252] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:57.253] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:162:0) after 1m0s
[15:50:57.407] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:162:0)
[15:50:57.407] Timer tick, asking Buildbucket for the build status
[15:50:57.836] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:57.836] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:163:0) after 1m0s
[15:51:58.074] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:163:0)
[15:51:58.074] Timer tick, asking Buildbucket for the build status
[15:51:58.193] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:58.193] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:164:0) after 1m0s
[15:52:58.354] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:164:0)
[15:52:58.354] Timer tick, asking Buildbucket for the build status
[15:52:58.419] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:58.419] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:165:0) after 1m0s
[15:53:58.617] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:165:0)
[15:53:58.617] Timer tick, asking Buildbucket for the build status
[15:53:58.700] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:58.700] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:166:0) after 1m0s
[15:54:58.869] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:166:0)
[15:54:58.869] Timer tick, asking Buildbucket for the build status
[15:54:58.996] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:58.996] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:167:0) after 1m0s
[15:55:59.053] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:167:0)
[15:55:59.053] Timer tick, asking Buildbucket for the build status
[15:55:59.224] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:59.224] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:168:0) after 1m0s
[15:56:59.341] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:168:0)
[15:56:59.341] Timer tick, asking Buildbucket for the build status
[15:56:59.395] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:59.395] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:169:0) after 1m0s
[15:57:59.488] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:169:0)
[15:57:59.488] Timer tick, asking Buildbucket for the build status
[15:57:59.553] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:59.553] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:170:0) after 1m0s
[15:58:59.668] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:170:0)
[15:58:59.668] Timer tick, asking Buildbucket for the build status
[15:58:59.732] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:59.732] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:171:0) after 1m0s
[15:59:59.785] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:171:0)
[15:59:59.785] Timer tick, asking Buildbucket for the build status
[15:59:59.857] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:59.857] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:172:0) after 1m0s
[16:00:59.933] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:172:0)
[16:00:59.933] Timer tick, asking Buildbucket for the build status
[16:01:00.115] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:00.115] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:173:0) after 1m0s
[16:02:00.154] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:173:0)
[16:02:00.154] Timer tick, asking Buildbucket for the build status
[16:02:00.240] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:00.240] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:174:0) after 1m0s
[16:03:00.327] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:174:0)
[16:03:00.327] Timer tick, asking Buildbucket for the build status
[16:03:00.466] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:00.466] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:175:0) after 1m0s
[16:04:00.474] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:175:0)
[16:04:00.474] Timer tick, asking Buildbucket for the build status
[16:04:00.645] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:00.645] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:176:0) after 1m0s
[16:05:00.738] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:176:0)
[16:05:00.738] Timer tick, asking Buildbucket for the build status
[16:05:00.825] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:00.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:177:0) after 1m0s
[16:06:00.914] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:177:0)
[16:06:00.914] Timer tick, asking Buildbucket for the build status
[16:06:01.059] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:01.059] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:178:0) after 1m0s
[16:07:01.093] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:178:0)
[16:07:01.093] Timer tick, asking Buildbucket for the build status
[16:07:01.202] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:01.202] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:179:0) after 1m0s
[16:08:01.503] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:179:0)
[16:08:01.503] Timer tick, asking Buildbucket for the build status
[16:08:01.585] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:01.585] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:180:0) after 1m0s
[16:09:01.969] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:180:0)
[16:09:01.969] Timer tick, asking Buildbucket for the build status
[16:09:02.081] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:02.081] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:181:0) after 1m0s
[16:10:02.173] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:181:0)
[16:10:02.173] Timer tick, asking Buildbucket for the build status
[16:10:02.305] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:02.305] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:182:0) after 1m0s
[16:11:02.438] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:182:0)
[16:11:02.438] Timer tick, asking Buildbucket for the build status
[16:11:02.570] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:02.570] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:183:0) after 1m0s
[16:12:02.800] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:183:0)
[16:12:02.800] Timer tick, asking Buildbucket for the build status
[16:12:03.003] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:03.003] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:184:0) after 1m0s
[16:13:03.090] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:184:0)
[16:13:03.091] Timer tick, asking Buildbucket for the build status
[16:13:03.438] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:03.438] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:185:0) after 1m0s
[16:14:03.499] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:185:0)
[16:14:03.499] Timer tick, asking Buildbucket for the build status
[16:14:03.560] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:03.560] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:186:0) after 1m0s
[16:15:03.583] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:186:0)
[16:15:03.583] Timer tick, asking Buildbucket for the build status
[16:15:03.641] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:03.641] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:187:0) after 1m0s
[16:16:03.666] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:187:0)
[16:16:03.666] Timer tick, asking Buildbucket for the build status
[16:16:03.773] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:03.773] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:188:0) after 1m0s
[16:17:03.846] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:188:0)
[16:17:03.846] Timer tick, asking Buildbucket for the build status
[16:17:03.918] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:03.918] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:189:0) after 1m0s
[16:18:03.976] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:189:0)
[16:18:03.976] Timer tick, asking Buildbucket for the build status
[16:18:04.145] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:04.145] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:190:0) after 1m0s
[16:19:04.229] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:190:0)
[16:19:04.229] Timer tick, asking Buildbucket for the build status
[16:19:04.660] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:04.660] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:191:0) after 1m0s
[16:20:04.925] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:191:0)
[16:20:04.925] Timer tick, asking Buildbucket for the build status
[16:20:05.234] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:05.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:192:0) after 1m0s
[16:21:05.292] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:192:0)
[16:21:05.292] Timer tick, asking Buildbucket for the build status
[16:21:05.390] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:05.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:193:0) after 1m0s
[16:22:05.606] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:193:0)
[16:22:05.606] Timer tick, asking Buildbucket for the build status
[16:22:06.021] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:06.021] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:194:0) after 1m0s
[16:23:06.384] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:194:0)
[16:23:06.384] Timer tick, asking Buildbucket for the build status
[16:23:06.643] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:06.643] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:195:0) after 1m0s
[16:24:06.832] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:195:0)
[16:24:06.832] Timer tick, asking Buildbucket for the build status
[16:24:06.988] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:06.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:196:0) after 1m0s
[16:25:07.076] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:196:0)
[16:25:07.076] Timer tick, asking Buildbucket for the build status
[16:25:07.245] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:07.245] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:197:0) after 1m0s
[16:26:07.343] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:197:0)
[16:26:07.344] Timer tick, asking Buildbucket for the build status
[16:26:07.730] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:07.730] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:198:0) after 1m0s
[16:27:07.821] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:198:0)
[16:27:07.821] Timer tick, asking Buildbucket for the build status
[16:27:07.974] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:07.974] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:199:0) after 1m0s
[16:28:08.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:199:0)
[16:28:08.016] Timer tick, asking Buildbucket for the build status
[16:28:08.364] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:08.364] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:200:0) after 1m0s
[16:29:08.580] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:200:0)
[16:29:08.580] Timer tick, asking Buildbucket for the build status
[16:29:08.720] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:08.720] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:201:0) after 1m0s
[16:30:08.796] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:201:0)
[16:30:08.796] Timer tick, asking Buildbucket for the build status
[16:30:08.858] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:08.858] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:202:0) after 1m0s
[16:31:09.081] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:202:0)
[16:31:09.081] Timer tick, asking Buildbucket for the build status
[16:31:09.661] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:09.661] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:203:0) after 1m0s
[16:32:09.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:203:0)
[16:32:09.798] Timer tick, asking Buildbucket for the build status
[16:32:09.995] Build 8856766991133248032: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:09.995] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9022197056136728080:204:0) after 1m0s
[16:32:42.739] Received PubSub notification, asking Buildbucket for the build status
[16:32:42.811] Build 8856766991133248032: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:32:42.811] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611937960958701",
"created_by": "project:chromium",
"created_ts": "1611925816369434",
"id": "8856766991133248032",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b72cf882000dfab1ab01faac718ef98904b3f639\"}}",
"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\": \"b72cf882000dfab1ab01faac718ef98904b3f639\", \"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-8-l60y\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 1440, \"got_angle_revision\": \"67a84f34080916b233598a7a6d26b9df18e7224a\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"b72cf882000dfab1ab01faac718ef98904b3f639\", \"got_revision_cp\": \"refs/heads/master@{#848488}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3e9b79ff42de0f5547809354de89e24c7a439e39\", \"got_v8_revision\": \"89b433d7c3defd04809fba9deb6f8885160044a4\", \"got_v8_revision_cp\": \"refs/heads/9.0.100@{#1}\", \"got_webrtc_revision\": \"54b925cfc2cd7c2f8f83e8e484f281aa87b92dfc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33090}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b72cf882000dfab1ab01faac718ef98904b3f639\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-8-l60y\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5673-2413400\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611925827936356",
"status": "COMPLETED",
"status_changed_ts": "1611937962270658",
"tags": [
"build_address:luci.chromium.ci/win32-official/1440",
"builder:win32-official",
"buildset:commit/git/b72cf882000dfab1ab01faac718ef98904b3f639",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b72cf882000dfab1ab01faac718ef98904b3f639",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022197056136728080",
"scheduler_job_id:chromium/win32-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8856766991133248032/+/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:5167158f1f772310",
"user_agent:luci-scheduler"
],
"updated_ts": "1611937962274582",
"url": "https://ci.chromium.org/b/8856766991133248032",
"utcnow_ts": "1611937962801950"
}
}
[16:32:42.811] Invocation finished in 3h22m30.597170075s with status SUCCEEDED