[05:22:23.133] New invocation is queued and will start shortly
[05:22:24.266] Starting the invocation (attempt 1)
[05:22:24.307] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:22:24.307] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:22:24.308] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037990360880985280",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037990360880985280",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd",
"gitiles_ref:refs/heads/master"
]
}
[05:22:25.116] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596864144419851",
"id": "8872560298859933232",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596864144965549",
"tags": [
"build_address:luci.chromium.ci/win-official/285",
"builder:win-official",
"buildset:commit/git/db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037990360880985280",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8872560298859933232/+/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": "1596864144965671",
"url": "https://ci.chromium.org/b/8872560298859933232",
"utcnow_ts": "1596864145108064"
}
}
[05:22:25.116] Task URL: https://ci.chromium.org/b/8872560298859933232
[05:22:25.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:2:0) after 1m0s
[05:22:27.692] Received PubSub notification, asking Buildbucket for the build status
[05:22:27.785] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:25.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:2:0)
[05:23:25.142] Timer tick, asking Buildbucket for the build status
[05:23:25.220] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:25.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:4:0) after 1m0s
[05:24:25.258] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:4:0)
[05:24:25.258] Timer tick, asking Buildbucket for the build status
[05:24:25.722] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:25.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:5:0) after 1m0s
[05:25:25.899] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:5:0)
[05:25:25.899] Timer tick, asking Buildbucket for the build status
[05:25:26.198] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:26.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:6:0) after 1m0s
[05:26:26.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:6:0)
[05:26:26.239] Timer tick, asking Buildbucket for the build status
[05:26:26.382] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:26.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:7:0) after 1m0s
[05:27:26.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:7:0)
[05:27:26.443] Timer tick, asking Buildbucket for the build status
[05:27:26.807] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:26.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:8:0) after 1m0s
[05:28:26.841] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:8:0)
[05:28:26.841] Timer tick, asking Buildbucket for the build status
[05:28:28.002] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:28.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:9:0) after 1m0s
[05:29:28.066] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:9:0)
[05:29:28.066] Timer tick, asking Buildbucket for the build status
[05:29:29.210] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:29.210] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:10:0) after 1m0s
[05:30:29.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:10:0)
[05:30:29.263] Timer tick, asking Buildbucket for the build status
[05:30:29.376] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:29.376] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:11:0) after 1m0s
[05:31:29.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:11:0)
[05:31:29.470] Timer tick, asking Buildbucket for the build status
[05:31:29.654] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:29.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:12:0) after 1m0s
[05:32:29.759] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:12:0)
[05:32:29.759] Timer tick, asking Buildbucket for the build status
[05:32:30.033] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:30.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:13:0) after 1m0s
[05:33:30.078] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:13:0)
[05:33:30.078] Timer tick, asking Buildbucket for the build status
[05:33:30.227] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:30.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:14:0) after 1m0s
[05:34:30.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:14:0)
[05:34:30.290] Timer tick, asking Buildbucket for the build status
[05:34:30.713] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:30.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:15:0) after 1m0s
[05:35:30.814] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:15:0)
[05:35:30.814] Timer tick, asking Buildbucket for the build status
[05:35:30.899] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:30.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:16:0) after 1m0s
[05:36:30.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:16:0)
[05:36:30.955] Timer tick, asking Buildbucket for the build status
[05:36:31.032] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:31.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:17:0) after 1m0s
[05:37:31.077] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:17:0)
[05:37:31.077] Timer tick, asking Buildbucket for the build status
[05:37:31.207] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:31.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:18:0) after 1m0s
[05:38:31.317] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:18:0)
[05:38:31.317] Timer tick, asking Buildbucket for the build status
[05:38:31.385] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:31.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:19:0) after 1m0s
[05:39:31.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:19:0)
[05:39:31.395] Timer tick, asking Buildbucket for the build status
[05:39:31.517] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:31.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:20:0) after 1m0s
[05:40:31.553] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:20:0)
[05:40:31.553] Timer tick, asking Buildbucket for the build status
[05:40:31.615] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:31.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:21:0) after 1m0s
[05:41:31.650] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:21:0)
[05:41:31.650] Timer tick, asking Buildbucket for the build status
[05:41:31.731] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:31.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:22:0) after 1m0s
[05:42:31.790] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:22:0)
[05:42:31.790] Timer tick, asking Buildbucket for the build status
[05:42:32.592] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:32.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:23:0) after 1m0s
[05:43:32.659] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:23:0)
[05:43:32.659] Timer tick, asking Buildbucket for the build status
[05:43:32.935] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:32.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:24:0) after 1m0s
[05:44:33.105] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:24:0)
[05:44:33.105] Timer tick, asking Buildbucket for the build status
[05:44:33.187] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:33.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:25:0) after 1m0s
[05:45:33.263] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:25:0)
[05:45:33.263] Timer tick, asking Buildbucket for the build status
[05:45:33.433] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:33.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:26:0) after 1m0s
[05:46:33.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:26:0)
[05:46:33.484] Timer tick, asking Buildbucket for the build status
[05:46:33.545] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:33.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:27:0) after 1m0s
[05:47:33.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:27:0)
[05:47:33.563] Timer tick, asking Buildbucket for the build status
[05:47:33.719] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:33.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:28:0) after 1m0s
[05:48:33.728] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:28:0)
[05:48:33.728] Timer tick, asking Buildbucket for the build status
[05:48:33.815] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:33.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:29:0) after 1m0s
[05:49:33.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:29:0)
[05:49:33.909] Timer tick, asking Buildbucket for the build status
[05:49:33.994] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:33.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:30:0) after 1m0s
[05:50:34.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:30:0)
[05:50:34.072] Timer tick, asking Buildbucket for the build status
[05:50:34.232] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:34.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:31:0) after 1m0s
[05:51:34.246] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:31:0)
[05:51:34.246] Timer tick, asking Buildbucket for the build status
[05:51:34.328] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:34.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:32:0) after 1m0s
[05:52:34.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:32:0)
[05:52:34.374] Timer tick, asking Buildbucket for the build status
[05:52:34.587] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:34.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:33:0) after 1m0s
[05:53:34.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:33:0)
[05:53:34.704] Timer tick, asking Buildbucket for the build status
[05:53:34.882] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:34.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:34:0) after 1m0s
[05:54:34.913] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:34:0)
[05:54:34.913] Timer tick, asking Buildbucket for the build status
[05:54:34.979] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:34.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:35:0) after 1m0s
[05:55:35.016] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:35:0)
[05:55:35.016] Timer tick, asking Buildbucket for the build status
[05:55:35.078] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:35.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:36:0) after 1m0s
[05:56:35.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:36:0)
[05:56:35.104] Timer tick, asking Buildbucket for the build status
[05:56:35.402] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:35.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:37:0) after 1m0s
[05:57:35.488] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:37:0)
[05:57:35.488] Timer tick, asking Buildbucket for the build status
[05:57:36.051] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:36.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:38:0) after 1m0s
[05:58:36.079] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:38:0)
[05:58:36.079] Timer tick, asking Buildbucket for the build status
[05:58:36.303] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:36.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:39:0) after 1m0s
[05:59:36.366] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:39:0)
[05:59:36.366] Timer tick, asking Buildbucket for the build status
[05:59:36.791] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:36.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:40:0) after 1m0s
[06:00:36.811] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:40:0)
[06:00:36.811] Timer tick, asking Buildbucket for the build status
[06:00:36.894] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:36.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:41:0) after 1m0s
[06:01:36.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:41:0)
[06:01:36.921] Timer tick, asking Buildbucket for the build status
[06:01:37.008] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:37.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:42:0) after 1m0s
[06:02:37.068] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:42:0)
[06:02:37.068] Timer tick, asking Buildbucket for the build status
[06:02:37.610] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:37.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:43:0) after 1m0s
[06:03:37.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:43:0)
[06:03:37.645] Timer tick, asking Buildbucket for the build status
[06:03:37.777] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:37.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:44:0) after 1m0s
[06:04:37.786] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:44:0)
[06:04:37.786] Timer tick, asking Buildbucket for the build status
[06:04:37.856] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:37.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:45:0) after 1m0s
[06:05:38.008] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:45:0)
[06:05:38.008] Timer tick, asking Buildbucket for the build status
[06:05:38.095] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:38.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:46:0) after 1m0s
[06:06:38.175] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:46:0)
[06:06:38.175] Timer tick, asking Buildbucket for the build status
[06:06:38.407] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:38.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:47:0) after 1m0s
[06:07:38.470] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:47:0)
[06:07:38.470] Timer tick, asking Buildbucket for the build status
[06:07:38.998] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:38.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:48:0) after 1m0s
[06:08:39.113] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:48:0)
[06:08:39.113] Timer tick, asking Buildbucket for the build status
[06:08:39.284] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:39.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:49:0) after 1m0s
[06:09:39.379] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:49:0)
[06:09:39.379] Timer tick, asking Buildbucket for the build status
[06:09:39.603] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:39.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:50:0) after 1m0s
[06:10:39.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:50:0)
[06:10:39.654] Timer tick, asking Buildbucket for the build status
[06:10:39.929] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:39.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:51:0) after 1m0s
[06:11:39.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:51:0)
[06:11:39.955] Timer tick, asking Buildbucket for the build status
[06:11:40.550] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:40.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:52:0) after 1m0s
[06:12:40.604] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:52:0)
[06:12:40.604] Timer tick, asking Buildbucket for the build status
[06:12:40.887] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:12:40.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:53:0) after 1m0s
[06:13:40.961] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:53:0)
[06:13:40.961] Timer tick, asking Buildbucket for the build status
[06:13:41.043] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:41.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:54:0) after 1m0s
[06:14:41.146] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:54:0)
[06:14:41.146] Timer tick, asking Buildbucket for the build status
[06:14:41.236] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:41.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:55:0) after 1m0s
[06:15:41.254] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:55:0)
[06:15:41.254] Timer tick, asking Buildbucket for the build status
[06:15:41.503] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:41.503] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:56:0) after 1m0s
[06:16:41.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:56:0)
[06:16:41.561] Timer tick, asking Buildbucket for the build status
[06:16:41.746] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:41.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:57:0) after 1m0s
[06:17:41.827] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:57:0)
[06:17:41.827] Timer tick, asking Buildbucket for the build status
[06:17:41.965] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:41.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:58:0) after 1m0s
[06:18:42.036] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:58:0)
[06:18:42.036] Timer tick, asking Buildbucket for the build status
[06:18:42.391] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:18:42.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:59:0) after 1m0s
[06:19:42.413] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:59:0)
[06:19:42.413] Timer tick, asking Buildbucket for the build status
[06:19:42.528] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:19:42.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:60:0) after 1m0s
[06:20:42.519] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:60:0)
[06:20:42.519] Timer tick, asking Buildbucket for the build status
[06:20:42.914] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:20:42.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:61:0) after 1m0s
[06:21:42.947] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:61:0)
[06:21:42.947] Timer tick, asking Buildbucket for the build status
[06:21:43.238] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:21:43.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:62:0) after 1m0s
[06:22:43.345] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:62:0)
[06:22:43.345] Timer tick, asking Buildbucket for the build status
[06:22:43.499] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:22:43.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:63:0) after 1m0s
[06:23:43.565] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:63:0)
[06:23:43.565] Timer tick, asking Buildbucket for the build status
[06:23:43.638] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:23:43.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:64:0) after 1m0s
[06:24:43.744] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:64:0)
[06:24:43.744] Timer tick, asking Buildbucket for the build status
[06:24:43.812] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:24:43.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:65:0) after 1m0s
[06:25:43.871] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:65:0)
[06:25:43.871] Timer tick, asking Buildbucket for the build status
[06:25:43.975] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:25:43.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:66:0) after 1m0s
[06:26:44.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:66:0)
[06:26:44.093] Timer tick, asking Buildbucket for the build status
[06:26:44.283] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:26:44.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:67:0) after 1m0s
[06:27:44.454] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:67:0)
[06:27:44.454] Timer tick, asking Buildbucket for the build status
[06:27:44.598] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:27:44.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:68:0) after 1m0s
[06:28:44.675] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:68:0)
[06:28:44.675] Timer tick, asking Buildbucket for the build status
[06:28:44.815] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:28:44.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:69:0) after 1m0s
[06:29:44.867] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:69:0)
[06:29:44.867] Timer tick, asking Buildbucket for the build status
[06:29:44.935] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:29:44.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:70:0) after 1m0s
[06:30:44.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:70:0)
[06:30:44.960] Timer tick, asking Buildbucket for the build status
[06:30:45.048] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:30:45.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:71:0) after 1m0s
[06:31:45.097] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:71:0)
[06:31:45.097] Timer tick, asking Buildbucket for the build status
[06:31:45.166] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:31:45.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:72:0) after 1m0s
[06:32:45.177] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:72:0)
[06:32:45.177] Timer tick, asking Buildbucket for the build status
[06:32:45.966] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:32:45.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:73:0) after 1m0s
[06:33:45.967] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:73:0)
[06:33:45.967] Timer tick, asking Buildbucket for the build status
[06:33:46.052] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:33:46.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:74:0) after 1m0s
[06:34:46.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:74:0)
[06:34:46.171] Timer tick, asking Buildbucket for the build status
[06:34:46.598] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:34:46.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:75:0) after 1m0s
[06:35:46.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:75:0)
[06:35:46.626] Timer tick, asking Buildbucket for the build status
[06:35:46.764] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:35:46.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:76:0) after 1m0s
[06:36:46.797] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:76:0)
[06:36:46.797] Timer tick, asking Buildbucket for the build status
[06:36:46.893] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:36:46.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:77:0) after 1m0s
[06:37:46.954] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:77:0)
[06:37:46.954] Timer tick, asking Buildbucket for the build status
[06:37:48.190] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:37:48.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:78:0) after 1m0s
[06:38:48.249] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:78:0)
[06:38:48.249] Timer tick, asking Buildbucket for the build status
[06:38:48.336] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:38:48.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:79:0) after 1m0s
[06:39:48.439] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:79:0)
[06:39:48.439] Timer tick, asking Buildbucket for the build status
[06:39:48.511] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:39:48.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:80:0) after 1m0s
[06:40:48.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:80:0)
[06:40:48.613] Timer tick, asking Buildbucket for the build status
[06:40:48.691] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:40:48.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:81:0) after 1m0s
[06:41:48.750] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:81:0)
[06:41:48.750] Timer tick, asking Buildbucket for the build status
[06:41:48.827] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:41:48.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:82:0) after 1m0s
[06:42:48.902] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:82:0)
[06:42:48.902] Timer tick, asking Buildbucket for the build status
[06:42:49.092] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:42:49.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:83:0) after 1m0s
[06:43:49.155] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:83:0)
[06:43:49.155] Timer tick, asking Buildbucket for the build status
[06:43:49.254] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:43:49.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:84:0) after 1m0s
[06:44:49.332] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:84:0)
[06:44:49.332] Timer tick, asking Buildbucket for the build status
[06:44:49.501] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:44:49.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:85:0) after 1m0s
[06:45:49.645] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:85:0)
[06:45:49.645] Timer tick, asking Buildbucket for the build status
[06:45:49.733] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:45:49.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:86:0) after 1m0s
[06:46:49.760] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:86:0)
[06:46:49.760] Timer tick, asking Buildbucket for the build status
[06:46:50.286] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:46:50.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:87:0) after 1m0s
[06:47:50.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:87:0)
[06:47:50.412] Timer tick, asking Buildbucket for the build status
[06:47:50.692] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:47:50.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:88:0) after 1m0s
[06:48:50.779] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:88:0)
[06:48:50.779] Timer tick, asking Buildbucket for the build status
[06:48:50.928] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:48:50.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:89:0) after 1m0s
[06:49:50.978] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:89:0)
[06:49:50.978] Timer tick, asking Buildbucket for the build status
[06:49:51.059] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:49:51.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:90:0) after 1m0s
[06:50:51.087] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:90:0)
[06:50:51.087] Timer tick, asking Buildbucket for the build status
[06:50:51.154] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:51.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:91:0) after 1m0s
[06:51:51.183] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:91:0)
[06:51:51.183] Timer tick, asking Buildbucket for the build status
[06:51:51.452] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:51.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:92:0) after 1m0s
[06:52:51.517] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:92:0)
[06:52:51.517] Timer tick, asking Buildbucket for the build status
[06:52:51.833] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:51.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:93:0) after 1m0s
[06:53:51.898] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:93:0)
[06:53:51.898] Timer tick, asking Buildbucket for the build status
[06:53:52.012] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:52.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:94:0) after 1m0s
[06:54:52.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:94:0)
[06:54:52.089] Timer tick, asking Buildbucket for the build status
[06:54:52.175] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:52.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:95:0) after 1m0s
[06:55:52.399] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:95:0)
[06:55:52.399] Timer tick, asking Buildbucket for the build status
[06:55:52.487] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:52.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:96:0) after 1m0s
[06:56:52.648] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:96:0)
[06:56:52.648] Timer tick, asking Buildbucket for the build status
[06:56:52.718] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:52.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:97:0) after 1m0s
[06:57:52.928] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:97:0)
[06:57:52.928] Timer tick, asking Buildbucket for the build status
[06:57:53.068] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:53.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:98:0) after 1m0s
[06:58:53.161] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:98:0)
[06:58:53.161] Timer tick, asking Buildbucket for the build status
[06:58:53.486] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:53.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:99:0) after 1m0s
[06:59:53.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:99:0)
[06:59:53.584] Timer tick, asking Buildbucket for the build status
[06:59:53.683] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:53.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:100:0) after 1m0s
[07:00:53.765] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:100:0)
[07:00:53.765] Timer tick, asking Buildbucket for the build status
[07:00:53.870] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:53.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:101:0) after 1m0s
[07:01:53.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:101:0)
[07:01:53.900] Timer tick, asking Buildbucket for the build status
[07:01:53.966] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:53.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:102:0) after 1m0s
[07:02:54.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:102:0)
[07:02:54.006] Timer tick, asking Buildbucket for the build status
[07:02:54.149] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:54.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:103:0) after 1m0s
[07:03:54.213] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:103:0)
[07:03:54.213] Timer tick, asking Buildbucket for the build status
[07:03:54.342] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:54.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:104:0) after 1m0s
[07:04:54.376] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:104:0)
[07:04:54.376] Timer tick, asking Buildbucket for the build status
[07:04:54.475] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:54.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:105:0) after 1m0s
[07:05:54.590] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:105:0)
[07:05:54.590] Timer tick, asking Buildbucket for the build status
[07:05:54.658] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:54.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:106:0) after 1m0s
[07:06:54.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:106:0)
[07:06:54.678] Timer tick, asking Buildbucket for the build status
[07:06:55.103] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:55.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:107:0) after 1m0s
[07:07:55.158] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:107:0)
[07:07:55.158] Timer tick, asking Buildbucket for the build status
[07:07:55.304] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:55.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:108:0) after 1m0s
[07:08:55.438] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:108:0)
[07:08:55.438] Timer tick, asking Buildbucket for the build status
[07:08:55.931] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:55.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:109:0) after 1m0s
[07:09:55.960] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:109:0)
[07:09:55.960] Timer tick, asking Buildbucket for the build status
[07:09:56.047] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:56.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:110:0) after 1m0s
[07:10:56.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:110:0)
[07:10:56.075] Timer tick, asking Buildbucket for the build status
[07:10:56.382] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:56.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:111:0) after 1m0s
[07:11:56.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:111:0)
[07:11:56.429] Timer tick, asking Buildbucket for the build status
[07:11:57.215] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:57.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:112:0) after 1m0s
[07:12:57.242] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:112:0)
[07:12:57.242] Timer tick, asking Buildbucket for the build status
[07:12:57.772] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:57.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:113:0) after 1m0s
[07:13:57.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:113:0)
[07:13:57.780] Timer tick, asking Buildbucket for the build status
[07:13:57.872] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:57.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:114:0) after 1m0s
[07:14:57.943] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:114:0)
[07:14:57.943] Timer tick, asking Buildbucket for the build status
[07:14:58.160] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:58.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:115:0) after 1m0s
[07:15:58.218] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:115:0)
[07:15:58.218] Timer tick, asking Buildbucket for the build status
[07:15:58.314] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:58.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:116:0) after 1m0s
[07:16:58.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:116:0)
[07:16:58.351] Timer tick, asking Buildbucket for the build status
[07:16:58.444] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:58.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:117:0) after 1m0s
[07:17:58.490] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:117:0)
[07:17:58.490] Timer tick, asking Buildbucket for the build status
[07:17:58.796] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:58.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:118:0) after 1m0s
[07:18:58.863] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:118:0)
[07:18:58.863] Timer tick, asking Buildbucket for the build status
[07:18:58.922] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:58.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:119:0) after 1m0s
[07:19:58.946] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:119:0)
[07:19:58.946] Timer tick, asking Buildbucket for the build status
[07:19:59.053] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:59.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:120:0) after 1m0s
[07:20:59.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:120:0)
[07:20:59.122] Timer tick, asking Buildbucket for the build status
[07:20:59.232] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:59.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:121:0) after 1m0s
[07:21:59.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:121:0)
[07:21:59.369] Timer tick, asking Buildbucket for the build status
[07:21:59.449] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:59.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:122:0) after 1m0s
[07:22:59.441] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:122:0)
[07:22:59.441] Timer tick, asking Buildbucket for the build status
[07:22:59.630] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:59.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:123:0) after 1m0s
[07:23:59.671] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:123:0)
[07:23:59.671] Timer tick, asking Buildbucket for the build status
[07:24:00.088] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:00.089] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:124:0) after 1m0s
[07:25:00.126] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:124:0)
[07:25:00.126] Timer tick, asking Buildbucket for the build status
[07:25:00.375] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:00.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:125:0) after 1m0s
[07:26:02.848] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:125:0)
[07:26:02.848] Timer tick, asking Buildbucket for the build status
[07:26:02.933] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:02.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:126:0) after 1m0s
[07:27:02.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:126:0)
[07:27:02.985] Timer tick, asking Buildbucket for the build status
[07:27:03.134] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:03.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:127:0) after 1m0s
[07:28:03.162] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:127:0)
[07:28:03.162] Timer tick, asking Buildbucket for the build status
[07:28:03.638] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:03.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:128:0) after 1m0s
[07:29:03.666] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:128:0)
[07:29:03.666] Timer tick, asking Buildbucket for the build status
[07:29:03.781] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:03.781] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:129:0) after 1m0s
[07:30:03.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:129:0)
[07:30:03.832] Timer tick, asking Buildbucket for the build status
[07:30:04.168] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:04.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:130:0) after 1m0s
[07:31:04.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:130:0)
[07:31:04.227] Timer tick, asking Buildbucket for the build status
[07:31:04.802] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:04.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:131:0) after 1m0s
[07:32:04.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:131:0)
[07:32:04.872] Timer tick, asking Buildbucket for the build status
[07:32:04.949] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:04.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:132:0) after 1m0s
[07:33:05.019] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:132:0)
[07:33:05.019] Timer tick, asking Buildbucket for the build status
[07:33:05.123] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:05.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:133:0) after 1m0s
[07:34:05.215] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:133:0)
[07:34:05.215] Timer tick, asking Buildbucket for the build status
[07:34:05.297] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:05.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:134:0) after 1m0s
[07:35:05.321] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:134:0)
[07:35:05.321] Timer tick, asking Buildbucket for the build status
[07:35:05.401] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:05.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:135:0) after 1m0s
[07:36:05.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:135:0)
[07:36:05.431] Timer tick, asking Buildbucket for the build status
[07:36:05.516] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:05.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:136:0) after 1m0s
[07:37:05.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:136:0)
[07:37:05.539] Timer tick, asking Buildbucket for the build status
[07:37:05.603] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:05.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:137:0) after 1m0s
[07:38:05.730] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:137:0)
[07:38:05.730] Timer tick, asking Buildbucket for the build status
[07:38:05.839] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:05.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:138:0) after 1m0s
[07:39:05.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:138:0)
[07:39:05.993] Timer tick, asking Buildbucket for the build status
[07:39:06.132] Build 8872560298859933232: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:06.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037990360880985280:139:0) after 1m0s
[07:39:38.472] Received PubSub notification, asking Buildbucket for the build status
[07:39:38.544] Build 8872560298859933232: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[07:39:38.544] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596872376873210",
"created_by": "project:chromium",
"created_ts": "1596864144419851",
"id": "8872560298859933232",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd\"}}",
"project": "chromium",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$build/chromium_tests\": {\"bucketed_triggers\": true}, \"$build/goma\": {\"enable_ats\": true, \"rpc_extra_params\": \"?prod\", \"server_host\": \"goma.chromium.org\"}, \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-9-ujc0\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 285, \"got_angle_revision\": \"9892e6a22610e9c9cd9422b7eb65abd11a6af75c\", \"got_dawn_revision\": \"101a5829d50f9509d514a13d49946a99c8f1c469\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd\", \"got_revision_cp\": \"refs/heads/master@{#796219}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"59b4828f293eb16f8983ae9ea0a8a0a84cf8174c\", \"got_v8_revision\": \"4f52dde736e657ac01cf74a6cdc38c752f9a5dae\", \"got_v8_revision_cp\": \"refs/heads/8.6.337@{#1}\", \"got_webrtc_revision\": \"4c2f9c90f42591f0935b496d61ee256ebf53198f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31887}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd\"}, \"swarming\": {\"bot_dimensions\": {\"builder\": [\"win-archive-dbg\", \"win-archive-rel\", \"win-official\", \"win32-archive-dbg\", \"win32-archive-rel\", \"win32-official\"], \"caches\": [\"builder_9b3ebe5fd1c5be87380c40d216747346a59b28cf26de183f0e8e2b32e944d3c8_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"win-dbg-9-ujc0\"], \"image\": [\"chrome-win10-1703-20073100-2eb11ddbc6e\"], \"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\": [\"5472-d0af7f3\"], \"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": "1596864146907357",
"status": "COMPLETED",
"status_changed_ts": "1596872377735651",
"tags": [
"build_address:luci.chromium.ci/win-official/285",
"builder:win-official",
"buildset:commit/git/db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/db9b55f9af9de1cec66fc1c318d778c3a5ca1fdd",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037990360880985280",
"scheduler_job_id:chromium/win-official",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8872560298859933232/+/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:4de556af6dcd1510",
"user_agent:luci-scheduler"
],
"updated_ts": "1596872377871780",
"url": "https://ci.chromium.org/b/8872560298859933232",
"utcnow_ts": "1596872378534060"
}
}
[07:39:38.544] Invocation finished in 2h17m15.436825285s with status SUCCEEDED