[18:12:28.615] New invocation is queued and will start shortly
[18:12:29.816] Starting the invocation (attempt 1)
[18:12:29.877] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:12:29.877] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:12:29.877] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028519826493886144",
"parameters_json": "{\"builder_name\":\"win32-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8307d05d7590ab932e350ea1ce2d8259bec20f70\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win32-official",
"scheduler_invocation_id:9028519826493886144",
"scheduler_job_id:chromium/win32-official",
"user_agent:luci-scheduler",
"buildset:commit/git/8307d05d7590ab932e350ea1ce2d8259bec20f70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8307d05d7590ab932e350ea1ce2d8259bec20f70",
"gitiles_ref:refs/heads/master"
]
}
[18:12:30.611] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605895950003633",
"id": "8863089764288349920",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8307d05d7590ab932e350ea1ce2d8259bec20f70\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605895950495170",
"tags": [
"build_address:luci.chromium.ci/win32-official/963",
"builder:win32-official",
"buildset:commit/git/8307d05d7590ab932e350ea1ce2d8259bec20f70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8307d05d7590ab932e350ea1ce2d8259bec20f70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028519826493886144",
"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/8863089764288349920/+/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": "1605895950495300",
"url": "https://ci.chromium.org/b/8863089764288349920",
"utcnow_ts": "1605895950604255"
}
}
[18:12:30.611] Task URL: https://ci.chromium.org/b/8863089764288349920
[18:12:30.611] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:2:0) after 1m0s
[18:13:10.565] Received PubSub notification, asking Buildbucket for the build status
[18:13:10.729] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:30.648] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:2:0)
[18:13:30.648] Timer tick, asking Buildbucket for the build status
[18:13:30.824] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:30.824] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:4:0) after 1m0s
[18:14:30.852] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:4:0)
[18:14:30.852] Timer tick, asking Buildbucket for the build status
[18:14:30.936] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:30.936] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:5:0) after 1m0s
[18:15:31.002] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:5:0)
[18:15:31.002] Timer tick, asking Buildbucket for the build status
[18:15:31.102] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:31.102] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:6:0) after 1m0s
[18:16:31.239] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:6:0)
[18:16:31.239] Timer tick, asking Buildbucket for the build status
[18:16:31.657] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:31.657] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:7:0) after 1m0s
[18:17:31.759] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:7:0)
[18:17:31.759] Timer tick, asking Buildbucket for the build status
[18:17:31.841] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:31.841] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:8:0) after 1m0s
[18:18:31.875] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:8:0)
[18:18:31.875] Timer tick, asking Buildbucket for the build status
[18:18:32.019] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:32.019] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:9:0) after 1m0s
[18:19:32.106] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:9:0)
[18:19:32.106] Timer tick, asking Buildbucket for the build status
[18:19:32.939] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:32.939] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:10:0) after 1m0s
[18:20:33.016] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:10:0)
[18:20:33.016] Timer tick, asking Buildbucket for the build status
[18:20:36.616] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:36.616] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:11:0) after 1m0s
[18:21:36.743] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:11:0)
[18:21:36.743] Timer tick, asking Buildbucket for the build status
[18:21:36.887] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:36.887] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:12:0) after 1m0s
[18:22:36.903] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:12:0)
[18:22:36.903] Timer tick, asking Buildbucket for the build status
[18:22:37.011] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:37.011] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:13:0) after 1m0s
[18:23:37.108] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:13:0)
[18:23:37.108] Timer tick, asking Buildbucket for the build status
[18:23:39.013] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:39.013] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:14:0) after 1m0s
[18:24:39.107] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:14:0)
[18:24:39.107] Timer tick, asking Buildbucket for the build status
[18:24:39.207] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:39.207] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:15:0) after 1m0s
[18:25:39.245] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:15:0)
[18:25:39.245] Timer tick, asking Buildbucket for the build status
[18:25:39.386] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:39.386] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:16:0) after 1m0s
[18:26:39.528] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:16:0)
[18:26:39.529] Timer tick, asking Buildbucket for the build status
[18:26:39.783] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:39.783] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:17:0) after 1m0s
[18:27:39.863] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:17:0)
[18:27:39.863] Timer tick, asking Buildbucket for the build status
[18:27:40.068] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:40.068] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:18:0) after 1m0s
[18:28:40.150] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:18:0)
[18:28:40.150] Timer tick, asking Buildbucket for the build status
[18:28:41.072] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:41.072] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:19:0) after 1m0s
[18:29:41.133] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:19:0)
[18:29:41.133] Timer tick, asking Buildbucket for the build status
[18:29:41.207] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:41.207] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:20:0) after 1m0s
[18:30:41.277] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:20:0)
[18:30:41.277] Timer tick, asking Buildbucket for the build status
[18:30:41.484] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:41.484] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:21:0) after 1m0s
[18:31:41.578] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:21:0)
[18:31:41.578] Timer tick, asking Buildbucket for the build status
[18:31:41.696] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:41.696] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:22:0) after 1m0s
[18:32:41.816] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:22:0)
[18:32:41.816] Timer tick, asking Buildbucket for the build status
[18:32:41.890] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:41.891] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:23:0) after 1m0s
[18:33:42.001] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:23:0)
[18:33:42.001] Timer tick, asking Buildbucket for the build status
[18:33:42.234] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:42.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:24:0) after 1m0s
[18:34:42.317] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:24:0)
[18:34:42.317] Timer tick, asking Buildbucket for the build status
[18:34:42.478] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:42.478] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:25:0) after 1m0s
[18:35:42.542] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:25:0)
[18:35:42.542] Timer tick, asking Buildbucket for the build status
[18:35:42.827] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:42.827] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:26:0) after 1m0s
[18:36:42.911] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:26:0)
[18:36:42.913] Timer tick, asking Buildbucket for the build status
[18:36:43.045] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:43.045] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:27:0) after 1m0s
[18:37:43.108] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:27:0)
[18:37:43.108] Timer tick, asking Buildbucket for the build status
[18:37:44.890] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:44.890] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:28:0) after 1m0s
[18:38:44.976] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:28:0)
[18:38:44.976] Timer tick, asking Buildbucket for the build status
[18:38:45.181] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:45.181] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:29:0) after 1m0s
[18:39:45.174] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:29:0)
[18:39:45.174] Timer tick, asking Buildbucket for the build status
[18:39:45.234] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:45.234] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:30:0) after 1m0s
[18:40:45.257] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:30:0)
[18:40:45.257] Timer tick, asking Buildbucket for the build status
[18:40:46.471] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:46.471] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:31:0) after 1m0s
[18:41:46.615] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:31:0)
[18:41:46.615] Timer tick, asking Buildbucket for the build status
[18:41:46.799] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:46.799] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:32:0) after 1m0s
[18:42:46.876] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:32:0)
[18:42:46.876] Timer tick, asking Buildbucket for the build status
[18:42:47.189] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:47.189] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:33:0) after 1m0s
[18:43:47.211] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:33:0)
[18:43:47.211] Timer tick, asking Buildbucket for the build status
[18:43:48.146] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:48.146] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:34:0) after 1m0s
[18:44:48.171] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:34:0)
[18:44:48.171] Timer tick, asking Buildbucket for the build status
[18:44:48.247] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:48.247] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:35:0) after 1m0s
[18:45:48.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:35:0)
[18:45:48.476] Timer tick, asking Buildbucket for the build status
[18:45:49.525] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:49.525] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:36:0) after 1m0s
[18:46:49.573] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:36:0)
[18:46:49.573] Timer tick, asking Buildbucket for the build status
[18:46:49.656] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:49.656] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:37:0) after 1m0s
[18:47:49.750] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:37:0)
[18:47:49.750] Timer tick, asking Buildbucket for the build status
[18:47:49.907] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:49.907] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:38:0) after 1m0s
[18:48:49.929] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:38:0)
[18:48:49.929] Timer tick, asking Buildbucket for the build status
[18:48:50.330] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:50.330] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:39:0) after 1m0s
[18:49:50.387] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:39:0)
[18:49:50.387] Timer tick, asking Buildbucket for the build status
[18:49:50.494] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:50.494] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:40:0) after 1m0s
[18:50:50.574] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:40:0)
[18:50:50.574] Timer tick, asking Buildbucket for the build status
[18:50:50.680] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:50.680] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:41:0) after 1m0s
[18:51:50.728] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:41:0)
[18:51:50.728] Timer tick, asking Buildbucket for the build status
[18:51:51.004] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:51.004] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:42:0) after 1m0s
[18:52:51.075] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:42:0)
[18:52:51.075] Timer tick, asking Buildbucket for the build status
[18:52:51.297] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:51.297] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:43:0) after 1m0s
[18:53:51.344] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:43:0)
[18:53:51.344] Timer tick, asking Buildbucket for the build status
[18:53:51.412] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:51.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:44:0) after 1m0s
[18:54:51.474] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:44:0)
[18:54:51.474] Timer tick, asking Buildbucket for the build status
[18:54:51.772] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:51.772] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:45:0) after 1m0s
[18:55:51.833] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:45:0)
[18:55:51.833] Timer tick, asking Buildbucket for the build status
[18:55:51.972] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:51.972] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:46:0) after 1m0s
[18:56:52.088] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:46:0)
[18:56:52.089] Timer tick, asking Buildbucket for the build status
[18:56:52.324] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:52.324] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:47:0) after 1m0s
[18:57:52.527] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:47:0)
[18:57:52.527] Timer tick, asking Buildbucket for the build status
[18:57:54.581] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:54.581] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:48:0) after 1m0s
[18:58:54.668] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:48:0)
[18:58:54.668] Timer tick, asking Buildbucket for the build status
[18:58:54.734] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:54.734] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:49:0) after 1m0s
[18:59:54.811] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:49:0)
[18:59:54.811] Timer tick, asking Buildbucket for the build status
[18:59:54.874] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:54.874] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:50:0) after 1m0s
[19:00:54.986] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:50:0)
[19:00:54.987] Timer tick, asking Buildbucket for the build status
[19:00:55.067] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:55.067] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:51:0) after 1m0s
[19:01:55.094] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:51:0)
[19:01:55.094] Timer tick, asking Buildbucket for the build status
[19:01:55.163] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:55.163] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:52:0) after 1m0s
[19:02:55.267] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:52:0)
[19:02:55.267] Timer tick, asking Buildbucket for the build status
[19:02:56.086] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:56.086] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:53:0) after 1m0s
[19:03:56.114] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:53:0)
[19:03:56.114] Timer tick, asking Buildbucket for the build status
[19:03:56.176] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:56.176] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:54:0) after 1m0s
[19:04:56.163] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:54:0)
[19:04:56.163] Timer tick, asking Buildbucket for the build status
[19:04:56.326] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:56.326] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:55:0) after 1m0s
[19:05:56.400] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:55:0)
[19:05:56.400] Timer tick, asking Buildbucket for the build status
[19:05:56.487] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:56.487] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:56:0) after 1m0s
[19:06:56.912] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:56:0)
[19:06:56.912] Timer tick, asking Buildbucket for the build status
[19:06:56.988] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:56.988] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:57:0) after 1m0s
[19:07:57.095] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:57:0)
[19:07:57.095] Timer tick, asking Buildbucket for the build status
[19:07:57.497] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:57.497] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:58:0) after 1m0s
[19:08:57.580] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:58:0)
[19:08:57.580] Timer tick, asking Buildbucket for the build status
[19:08:57.654] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:57.654] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:59:0) after 1m0s
[19:09:57.664] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:59:0)
[19:09:57.664] Timer tick, asking Buildbucket for the build status
[19:09:57.729] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:57.729] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:60:0) after 1m0s
[19:10:57.880] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:60:0)
[19:10:57.880] Timer tick, asking Buildbucket for the build status
[19:10:58.069] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:58.069] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:61:0) after 1m0s
[19:11:58.370] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:61:0)
[19:11:58.370] Timer tick, asking Buildbucket for the build status
[19:11:58.447] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:58.447] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:62:0) after 1m0s
[19:12:58.584] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:62:0)
[19:12:58.584] Timer tick, asking Buildbucket for the build status
[19:12:58.690] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:58.690] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:63:0) after 1m0s
[19:13:58.861] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:63:0)
[19:13:58.862] Timer tick, asking Buildbucket for the build status
[19:13:59.144] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:59.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:64:0) after 1m0s
[19:14:59.413] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:64:0)
[19:14:59.413] Timer tick, asking Buildbucket for the build status
[19:14:59.517] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:59.517] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:65:0) after 1m0s
[19:15:59.711] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:65:0)
[19:15:59.711] Timer tick, asking Buildbucket for the build status
[19:15:59.826] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:59.826] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:66:0) after 1m0s
[19:16:59.912] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:66:0)
[19:16:59.912] Timer tick, asking Buildbucket for the build status
[19:17:00.261] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:00.261] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:67:0) after 1m0s
[19:18:00.285] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:67:0)
[19:18:00.285] Timer tick, asking Buildbucket for the build status
[19:18:00.823] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:00.823] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:68:0) after 1m0s
[19:19:00.850] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:68:0)
[19:19:00.850] Timer tick, asking Buildbucket for the build status
[19:19:00.921] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:00.921] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:69:0) after 1m0s
[19:20:01.183] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:69:0)
[19:20:01.183] Timer tick, asking Buildbucket for the build status
[19:20:01.360] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:01.360] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:70:0) after 1m0s
[19:21:01.472] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:70:0)
[19:21:01.472] Timer tick, asking Buildbucket for the build status
[19:21:01.538] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:01.539] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:71:0) after 1m0s
[19:22:01.690] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:71:0)
[19:22:01.690] Timer tick, asking Buildbucket for the build status
[19:22:01.854] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:01.854] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:72:0) after 1m0s
[19:23:01.900] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:72:0)
[19:23:01.900] Timer tick, asking Buildbucket for the build status
[19:23:02.056] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:02.056] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:73:0) after 1m0s
[19:24:02.075] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:73:0)
[19:24:02.075] Timer tick, asking Buildbucket for the build status
[19:24:02.350] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:02.350] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:74:0) after 1m0s
[19:25:02.405] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:74:0)
[19:25:02.405] Timer tick, asking Buildbucket for the build status
[19:25:02.632] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:02.632] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:75:0) after 1m0s
[19:26:02.754] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:75:0)
[19:26:02.754] Timer tick, asking Buildbucket for the build status
[19:26:02.825] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:02.825] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:76:0) after 1m0s
[19:27:02.883] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:76:0)
[19:27:02.883] Timer tick, asking Buildbucket for the build status
[19:27:03.019] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:03.019] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:77:0) after 1m0s
[19:28:03.073] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:77:0)
[19:28:03.073] Timer tick, asking Buildbucket for the build status
[19:28:03.360] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:03.360] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:78:0) after 1m0s
[19:29:03.398] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:78:0)
[19:29:03.398] Timer tick, asking Buildbucket for the build status
[19:29:03.468] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:03.468] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:79:0) after 1m0s
[19:30:03.540] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:79:0)
[19:30:03.540] Timer tick, asking Buildbucket for the build status
[19:30:03.684] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:03.684] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:80:0) after 1m0s
[19:31:03.746] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:80:0)
[19:31:03.746] Timer tick, asking Buildbucket for the build status
[19:31:04.083] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:04.083] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:81:0) after 1m0s
[19:32:04.290] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:81:0)
[19:32:04.290] Timer tick, asking Buildbucket for the build status
[19:32:06.404] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:06.404] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:82:0) after 1m0s
[19:33:06.716] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:82:0)
[19:33:06.716] Timer tick, asking Buildbucket for the build status
[19:33:06.870] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:06.870] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:83:0) after 1m0s
[19:34:06.948] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:83:0)
[19:34:06.948] Timer tick, asking Buildbucket for the build status
[19:34:07.026] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:07.026] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:84:0) after 1m0s
[19:35:07.261] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:84:0)
[19:35:07.261] Timer tick, asking Buildbucket for the build status
[19:35:07.328] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:07.329] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:85:0) after 1m0s
[19:36:07.530] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:85:0)
[19:36:07.530] Timer tick, asking Buildbucket for the build status
[19:36:07.611] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:07.611] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:86:0) after 1m0s
[19:37:07.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:86:0)
[19:37:07.953] Timer tick, asking Buildbucket for the build status
[19:37:08.071] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:08.071] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:87:0) after 1m0s
[19:38:08.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:87:0)
[19:38:08.323] Timer tick, asking Buildbucket for the build status
[19:38:08.479] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:08.479] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:88:0) after 1m0s
[19:39:08.633] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:88:0)
[19:39:08.633] Timer tick, asking Buildbucket for the build status
[19:39:08.908] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:08.908] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:89:0) after 1m0s
[19:40:08.946] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:89:0)
[19:40:08.946] Timer tick, asking Buildbucket for the build status
[19:40:09.019] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:09.019] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:90:0) after 1m0s
[19:41:09.158] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:90:0)
[19:41:09.158] Timer tick, asking Buildbucket for the build status
[19:41:09.238] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:09.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:91:0) after 1m0s
[19:42:09.347] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:91:0)
[19:42:09.347] Timer tick, asking Buildbucket for the build status
[19:42:09.852] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:09.852] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:92:0) after 1m0s
[19:43:09.885] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:92:0)
[19:43:09.885] Timer tick, asking Buildbucket for the build status
[19:43:09.992] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:09.992] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:93:0) after 1m0s
[19:44:10.119] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:93:0)
[19:44:10.119] Timer tick, asking Buildbucket for the build status
[19:44:10.173] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:10.173] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:94:0) after 1m0s
[19:45:10.220] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:94:0)
[19:45:10.220] Timer tick, asking Buildbucket for the build status
[19:45:10.297] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:10.297] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:95:0) after 1m0s
[19:46:10.363] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:95:0)
[19:46:10.363] Timer tick, asking Buildbucket for the build status
[19:46:10.440] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:10.440] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:96:0) after 1m0s
[19:47:10.521] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:96:0)
[19:47:10.521] Timer tick, asking Buildbucket for the build status
[19:47:10.590] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:10.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:97:0) after 1m0s
[19:48:10.669] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:97:0)
[19:48:10.669] Timer tick, asking Buildbucket for the build status
[19:48:10.994] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:10.994] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:98:0) after 1m0s
[19:49:11.047] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:98:0)
[19:49:11.047] Timer tick, asking Buildbucket for the build status
[19:49:11.111] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:11.111] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:99:0) after 1m0s
[19:50:11.139] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:99:0)
[19:50:11.139] Timer tick, asking Buildbucket for the build status
[19:50:11.200] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:11.200] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:100:0) after 1m0s
[19:51:11.232] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:100:0)
[19:51:11.232] Timer tick, asking Buildbucket for the build status
[19:51:11.307] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:11.307] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:101:0) after 1m0s
[19:52:11.371] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:101:0)
[19:52:11.371] Timer tick, asking Buildbucket for the build status
[19:52:11.603] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:11.603] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:102:0) after 1m0s
[19:53:11.679] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:102:0)
[19:53:11.679] Timer tick, asking Buildbucket for the build status
[19:53:11.792] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:11.792] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:103:0) after 1m0s
[19:54:11.916] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:103:0)
[19:54:11.916] Timer tick, asking Buildbucket for the build status
[19:54:12.024] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:12.024] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:104:0) after 1m0s
[19:55:12.082] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:104:0)
[19:55:12.082] Timer tick, asking Buildbucket for the build status
[19:55:12.144] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:12.144] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:105:0) after 1m0s
[19:56:12.176] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:105:0)
[19:56:12.176] Timer tick, asking Buildbucket for the build status
[19:56:12.354] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:12.354] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:106:0) after 1m0s
[19:57:12.414] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:106:0)
[19:57:12.414] Timer tick, asking Buildbucket for the build status
[19:57:13.515] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:13.515] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:107:0) after 1m0s
[19:58:13.715] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:107:0)
[19:58:13.715] Timer tick, asking Buildbucket for the build status
[19:58:13.827] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:13.827] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:108:0) after 1m0s
[19:59:13.997] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:108:0)
[19:59:13.997] Timer tick, asking Buildbucket for the build status
[19:59:14.109] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:14.109] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:109:0) after 1m0s
[20:00:14.170] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:109:0)
[20:00:14.170] Timer tick, asking Buildbucket for the build status
[20:00:14.413] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:14.413] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:110:0) after 1m0s
[20:01:14.535] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:110:0)
[20:01:14.535] Timer tick, asking Buildbucket for the build status
[20:01:14.596] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:14.596] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:111:0) after 1m0s
[20:02:14.672] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:111:0)
[20:02:14.672] Timer tick, asking Buildbucket for the build status
[20:02:14.848] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:14.848] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:112:0) after 1m0s
[20:03:14.922] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:112:0)
[20:03:14.922] Timer tick, asking Buildbucket for the build status
[20:03:15.113] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:15.114] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:113:0) after 1m0s
[20:04:15.140] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:113:0)
[20:04:15.140] Timer tick, asking Buildbucket for the build status
[20:04:15.282] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:15.282] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:114:0) after 1m0s
[20:05:15.312] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:114:0)
[20:05:15.312] Timer tick, asking Buildbucket for the build status
[20:05:15.668] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:15.668] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:115:0) after 1m0s
[20:06:15.700] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:115:0)
[20:06:15.700] Timer tick, asking Buildbucket for the build status
[20:06:15.888] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:15.888] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:116:0) after 1m0s
[20:07:15.934] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:116:0)
[20:07:15.934] Timer tick, asking Buildbucket for the build status
[20:07:16.032] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:16.032] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:117:0) after 1m0s
[20:08:16.061] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:117:0)
[20:08:16.061] Timer tick, asking Buildbucket for the build status
[20:08:16.250] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:16.250] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:118:0) after 1m0s
[20:09:16.280] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:118:0)
[20:09:16.280] Timer tick, asking Buildbucket for the build status
[20:09:16.375] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:16.375] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:119:0) after 1m0s
[20:10:16.469] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:119:0)
[20:10:16.469] Timer tick, asking Buildbucket for the build status
[20:10:16.572] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:16.572] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:120:0) after 1m0s
[20:11:16.586] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:120:0)
[20:11:16.586] Timer tick, asking Buildbucket for the build status
[20:11:16.669] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:16.669] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:121:0) after 1m0s
[20:12:16.699] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:121:0)
[20:12:16.699] Timer tick, asking Buildbucket for the build status
[20:12:16.794] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:16.794] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:122:0) after 1m0s
[20:13:16.873] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:122:0)
[20:13:16.873] Timer tick, asking Buildbucket for the build status
[20:13:17.333] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:17.333] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:123:0) after 1m0s
[20:14:17.379] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:123:0)
[20:14:17.379] Timer tick, asking Buildbucket for the build status
[20:14:17.489] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:17.489] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:124:0) after 1m0s
[20:15:17.581] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:124:0)
[20:15:17.581] Timer tick, asking Buildbucket for the build status
[20:15:28.271] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:28.271] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:125:0) after 1m0s
[20:16:28.359] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:125:0)
[20:16:28.359] Timer tick, asking Buildbucket for the build status
[20:16:28.489] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:28.490] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:126:0) after 1m0s
[20:17:28.693] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:126:0)
[20:17:28.693] Timer tick, asking Buildbucket for the build status
[20:17:28.784] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:28.784] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:127:0) after 1m0s
[20:18:28.878] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:127:0)
[20:18:28.878] Timer tick, asking Buildbucket for the build status
[20:18:29.059] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:29.059] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:128:0) after 1m0s
[20:19:29.109] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:128:0)
[20:19:29.109] Timer tick, asking Buildbucket for the build status
[20:19:30.922] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:30.922] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:129:0) after 1m0s
[20:20:30.997] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:129:0)
[20:20:30.997] Timer tick, asking Buildbucket for the build status
[20:20:31.217] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:31.217] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:130:0) after 1m0s
[20:21:31.273] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:130:0)
[20:21:31.273] Timer tick, asking Buildbucket for the build status
[20:21:32.565] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:32.565] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:131:0) after 1m0s
[20:22:32.633] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:131:0)
[20:22:32.633] Timer tick, asking Buildbucket for the build status
[20:22:32.788] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:32.788] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:132:0) after 1m0s
[20:23:32.835] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:132:0)
[20:23:32.835] Timer tick, asking Buildbucket for the build status
[20:23:33.211] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:33.211] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:133:0) after 1m0s
[20:24:33.238] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:133:0)
[20:24:33.238] Timer tick, asking Buildbucket for the build status
[20:24:33.625] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:33.625] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:134:0) after 1m0s
[20:25:33.733] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:134:0)
[20:25:33.733] Timer tick, asking Buildbucket for the build status
[20:25:33.791] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:33.791] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:135:0) after 1m0s
[20:26:33.869] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:135:0)
[20:26:33.869] Timer tick, asking Buildbucket for the build status
[20:26:34.004] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:34.004] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:136:0) after 1m0s
[20:27:34.131] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:136:0)
[20:27:34.131] Timer tick, asking Buildbucket for the build status
[20:27:34.192] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:34.192] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:137:0) after 1m0s
[20:28:34.204] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:137:0)
[20:28:34.204] Timer tick, asking Buildbucket for the build status
[20:28:34.545] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:34.545] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:138:0) after 1m0s
[20:29:34.611] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:138:0)
[20:29:34.611] Timer tick, asking Buildbucket for the build status
[20:29:34.747] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:34.748] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:139:0) after 1m0s
[20:30:34.809] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:139:0)
[20:30:34.809] Timer tick, asking Buildbucket for the build status
[20:30:35.016] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:35.016] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:140:0) after 1m0s
[20:31:35.088] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:140:0)
[20:31:35.088] Timer tick, asking Buildbucket for the build status
[20:31:35.163] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:35.163] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:141:0) after 1m0s
[20:32:35.216] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:141:0)
[20:32:35.216] Timer tick, asking Buildbucket for the build status
[20:32:35.292] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:35.292] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:142:0) after 1m0s
[20:33:35.337] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:142:0)
[20:33:35.337] Timer tick, asking Buildbucket for the build status
[20:33:35.975] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:35.975] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:143:0) after 1m0s
[20:34:36.006] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:143:0)
[20:34:36.006] Timer tick, asking Buildbucket for the build status
[20:34:36.106] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:36.106] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:144:0) after 1m0s
[20:35:36.147] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:144:0)
[20:35:36.147] Timer tick, asking Buildbucket for the build status
[20:35:36.207] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:36.207] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:145:0) after 1m0s
[20:36:36.287] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:145:0)
[20:36:36.287] Timer tick, asking Buildbucket for the build status
[20:36:36.343] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:36.343] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:146:0) after 1m0s
[20:37:36.406] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:146:0)
[20:37:36.408] Timer tick, asking Buildbucket for the build status
[20:37:36.552] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:36.553] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:147:0) after 1m0s
[20:38:36.589] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:147:0)
[20:38:36.589] Timer tick, asking Buildbucket for the build status
[20:38:37.360] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:37.360] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:148:0) after 1m0s
[20:39:37.357] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:148:0)
[20:39:37.357] Timer tick, asking Buildbucket for the build status
[20:39:37.639] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:37.639] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:149:0) after 1m0s
[20:40:37.683] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:149:0)
[20:40:37.683] Timer tick, asking Buildbucket for the build status
[20:40:37.853] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:37.853] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:150:0) after 1m0s
[20:41:37.855] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:150:0)
[20:41:37.855] Timer tick, asking Buildbucket for the build status
[20:41:37.947] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:37.947] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:151:0) after 1m0s
[20:42:37.941] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:151:0)
[20:42:37.941] Timer tick, asking Buildbucket for the build status
[20:42:38.129] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:38.129] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:152:0) after 1m0s
[20:43:38.156] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:152:0)
[20:43:38.156] Timer tick, asking Buildbucket for the build status
[20:43:38.285] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:38.285] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:153:0) after 1m0s
[20:44:38.328] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:153:0)
[20:44:38.328] Timer tick, asking Buildbucket for the build status
[20:44:38.397] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:38.397] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:154:0) after 1m0s
[20:45:38.428] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:154:0)
[20:45:38.428] Timer tick, asking Buildbucket for the build status
[20:45:38.785] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:38.785] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:155:0) after 1m0s
[20:46:38.858] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:155:0)
[20:46:38.858] Timer tick, asking Buildbucket for the build status
[20:46:39.592] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:39.592] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:156:0) after 1m0s
[20:47:39.621] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:156:0)
[20:47:39.621] Timer tick, asking Buildbucket for the build status
[20:47:39.707] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:39.707] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:157:0) after 1m0s
[20:48:39.739] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:157:0)
[20:48:39.739] Timer tick, asking Buildbucket for the build status
[20:48:39.841] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:39.841] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:158:0) after 1m0s
[20:49:39.906] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:158:0)
[20:49:39.906] Timer tick, asking Buildbucket for the build status
[20:49:40.000] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:40.000] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:159:0) after 1m0s
[20:50:40.043] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:159:0)
[20:50:40.043] Timer tick, asking Buildbucket for the build status
[20:50:40.112] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:40.112] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:160:0) after 1m0s
[20:51:40.143] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:160:0)
[20:51:40.143] Timer tick, asking Buildbucket for the build status
[20:51:40.214] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:40.214] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:161:0) after 1m0s
[20:52:40.300] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:161:0)
[20:52:40.300] Timer tick, asking Buildbucket for the build status
[20:52:40.383] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:40.383] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:162:0) after 1m0s
[20:53:40.451] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:162:0)
[20:53:40.451] Timer tick, asking Buildbucket for the build status
[20:53:40.580] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:40.580] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:163:0) after 1m0s
[20:54:40.626] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:163:0)
[20:54:40.626] Timer tick, asking Buildbucket for the build status
[20:54:40.693] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:40.693] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:164:0) after 1m0s
[20:55:40.798] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:164:0)
[20:55:40.798] Timer tick, asking Buildbucket for the build status
[20:55:40.885] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:40.885] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:165:0) after 1m0s
[20:56:41.074] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:165:0)
[20:56:41.074] Timer tick, asking Buildbucket for the build status
[20:56:41.157] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:41.157] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:166:0) after 1m0s
[20:57:41.232] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:166:0)
[20:57:41.232] Timer tick, asking Buildbucket for the build status
[20:57:41.381] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:41.381] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:167:0) after 1m0s
[20:58:41.476] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:167:0)
[20:58:41.476] Timer tick, asking Buildbucket for the build status
[20:58:41.606] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:41.606] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:168:0) after 1m0s
[20:59:41.753] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:168:0)
[20:59:41.753] Timer tick, asking Buildbucket for the build status
[20:59:41.829] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:41.829] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:169:0) after 1m0s
[21:00:41.974] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:169:0)
[21:00:41.974] Timer tick, asking Buildbucket for the build status
[21:00:42.263] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:42.263] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:170:0) after 1m0s
[21:01:42.323] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:170:0)
[21:01:42.323] Timer tick, asking Buildbucket for the build status
[21:01:42.412] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:42.412] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:171:0) after 1m0s
[21:02:42.504] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:171:0)
[21:02:42.504] Timer tick, asking Buildbucket for the build status
[21:02:42.577] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:42.577] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:172:0) after 1m0s
[21:03:42.762] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:172:0)
[21:03:42.762] Timer tick, asking Buildbucket for the build status
[21:03:42.878] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:42.878] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:173:0) after 1m0s
[21:04:42.953] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:173:0)
[21:04:42.953] Timer tick, asking Buildbucket for the build status
[21:04:43.065] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:43.065] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:174:0) after 1m0s
[21:05:43.175] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:174:0)
[21:05:43.175] Timer tick, asking Buildbucket for the build status
[21:05:43.238] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:43.238] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:175:0) after 1m0s
[21:06:43.342] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:175:0)
[21:06:43.342] Timer tick, asking Buildbucket for the build status
[21:06:43.467] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:43.467] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:176:0) after 1m0s
[21:07:43.525] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:176:0)
[21:07:43.525] Timer tick, asking Buildbucket for the build status
[21:07:43.593] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:43.593] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:177:0) after 1m0s
[21:08:43.626] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:177:0)
[21:08:43.626] Timer tick, asking Buildbucket for the build status
[21:08:43.745] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:43.745] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:178:0) after 1m0s
[21:09:43.773] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:178:0)
[21:09:43.773] Timer tick, asking Buildbucket for the build status
[21:09:43.888] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:43.888] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:179:0) after 1m0s
[21:10:43.963] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:179:0)
[21:10:43.963] Timer tick, asking Buildbucket for the build status
[21:10:44.033] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:44.033] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:180:0) after 1m0s
[21:11:44.144] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:180:0)
[21:11:44.144] Timer tick, asking Buildbucket for the build status
[21:11:44.242] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:44.242] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:181:0) after 1m0s
[21:12:44.300] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:181:0)
[21:12:44.300] Timer tick, asking Buildbucket for the build status
[21:12:44.435] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:44.435] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:182:0) after 1m0s
[21:13:44.543] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:182:0)
[21:13:44.543] Timer tick, asking Buildbucket for the build status
[21:13:44.648] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:44.648] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:183:0) after 1m0s
[21:14:44.699] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:183:0)
[21:14:44.699] Timer tick, asking Buildbucket for the build status
[21:14:44.837] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:44.837] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:184:0) after 1m0s
[21:15:44.850] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:184:0)
[21:15:44.850] Timer tick, asking Buildbucket for the build status
[21:15:44.959] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:44.959] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:185:0) after 1m0s
[21:16:44.990] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:185:0)
[21:16:44.991] Timer tick, asking Buildbucket for the build status
[21:16:47.308] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:47.308] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:186:0) after 1m0s
[21:17:47.339] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:186:0)
[21:17:47.339] Timer tick, asking Buildbucket for the build status
[21:17:47.390] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:47.390] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:187:0) after 1m0s
[21:18:47.450] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:187:0)
[21:18:47.450] Timer tick, asking Buildbucket for the build status
[21:18:47.590] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:47.590] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:188:0) after 1m0s
[21:19:47.655] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:188:0)
[21:19:47.655] Timer tick, asking Buildbucket for the build status
[21:19:47.712] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:47.712] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:189:0) after 1m0s
[21:20:47.769] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:189:0)
[21:20:47.769] Timer tick, asking Buildbucket for the build status
[21:20:47.843] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:47.843] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:190:0) after 1m0s
[21:21:47.892] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:190:0)
[21:21:47.892] Timer tick, asking Buildbucket for the build status
[21:21:47.973] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:47.973] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:191:0) after 1m0s
[21:22:48.000] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:191:0)
[21:22:48.000] Timer tick, asking Buildbucket for the build status
[21:22:48.062] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:48.062] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:192:0) after 1m0s
[21:23:48.056] Handling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:192:0)
[21:23:48.056] Timer tick, asking Buildbucket for the build status
[21:23:48.169] Build 8863089764288349920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:48.169] Scheduling timer "check-buildbucket-build-status" (chromium/win32-official:9028519826493886144:193:0) after 1m0s
[21:24:14.974] Received PubSub notification, asking Buildbucket for the build status
[21:24:15.150] Build 8863089764288349920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:24:15.150] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605907454169132",
"created_by": "project:chromium",
"created_ts": "1605895950003633",
"id": "8863089764288349920",
"parameters_json": "{\"builder_name\": \"win32-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8307d05d7590ab932e350ea1ce2d8259bec20f70\"}}",
"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\": \"8307d05d7590ab932e350ea1ce2d8259bec20f70\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-2-xx5d\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium\", \"buildername\": \"win32-official\", \"buildnumber\": 963, \"got_angle_revision\": \"7784fce44d7b02b849b1e72a830296356f2d5791\", \"got_dawn_revision\": \"d2953cff41e76f2a48bb43c0a62b9ce1936496a5\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"8307d05d7590ab932e350ea1ce2d8259bec20f70\", \"got_revision_cp\": \"refs/heads/master@{#829714}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"de9e649438ed3cc2ecf5c30ebf08f033d01270e6\", \"got_v8_revision\": \"94b2c943f7f37e0450e7c5d029fbd22a9d7f6946\", \"got_v8_revision_cp\": \"refs/heads/8.9.14@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"mirrored_builders\": [\"tryserver.chromium:win32-official\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8307d05d7590ab932e350ea1ce2d8259bec20f70\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_f6b49936a2965b3cdad8f63ce13313d0c4b82119a9acfffbfe199e91c26d0c88_v2\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-2-xx5d\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5637-4d8b832\"], \"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": "1605895989073306",
"status": "COMPLETED",
"status_changed_ts": "1605907454558846",
"tags": [
"build_address:luci.chromium.ci/win32-official/963",
"builder:win32-official",
"buildset:commit/git/8307d05d7590ab932e350ea1ce2d8259bec20f70",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8307d05d7590ab932e350ea1ce2d8259bec20f70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028519826493886144",
"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/8863089764288349920/+/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:4fffad4a488b0810",
"user_agent:luci-scheduler"
],
"updated_ts": "1605907454566972",
"url": "https://ci.chromium.org/b/8863089764288349920",
"utcnow_ts": "1605907455092749"
}
}
[21:24:15.151] Invocation finished in 3h11m46.634162393s with status SUCCEEDED