[13:02:17.337] New invocation is queued and will start shortly
[13:02:18.742] Starting the invocation (attempt 1)
[13:02:18.786] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:02:18.786] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:02:18.786] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9037599038385898576",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9037599038385898576",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac",
"gitiles_ref:refs/heads/master"
]
}
[13:02:19.821] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1597237338876298",
"id": "8872168976108518016",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1597237339581618",
"tags": [
"build_address:luci.chromium.ci/win-official/323",
"builder:win-official",
"buildset:commit/git/dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037599038385898576",
"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/8872168976108518016/+/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": "1597237339581814",
"url": "https://ci.chromium.org/b/8872168976108518016",
"utcnow_ts": "1597237339797393"
}
}
[13:02:19.821] Task URL: https://ci.chromium.org/b/8872168976108518016
[13:02:19.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:2:0) after 1m0s
[13:02:23.889] Received PubSub notification, asking Buildbucket for the build status
[13:02:24.023] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:19.985] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:2:0)
[13:03:19.985] Timer tick, asking Buildbucket for the build status
[13:03:20.092] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:20.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:4:0) after 1m0s
[13:04:20.205] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:4:0)
[13:04:20.205] Timer tick, asking Buildbucket for the build status
[13:04:20.440] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:20.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:5:0) after 1m0s
[13:05:20.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:5:0)
[13:05:20.568] Timer tick, asking Buildbucket for the build status
[13:05:20.712] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:20.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:6:0) after 1m0s
[13:06:20.865] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:6:0)
[13:06:20.865] Timer tick, asking Buildbucket for the build status
[13:06:21.046] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:21.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:7:0) after 1m0s
[13:07:21.102] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:7:0)
[13:07:21.102] Timer tick, asking Buildbucket for the build status
[13:07:21.191] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:21.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:8:0) after 1m0s
[13:08:21.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:8:0)
[13:08:21.214] Timer tick, asking Buildbucket for the build status
[13:08:21.317] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:21.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:9:0) after 1m0s
[13:09:21.462] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:9:0)
[13:09:21.462] Timer tick, asking Buildbucket for the build status
[13:09:22.004] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:22.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:10:0) after 1m0s
[13:10:22.142] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:10:0)
[13:10:22.142] Timer tick, asking Buildbucket for the build status
[13:10:22.326] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:22.327] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:11:0) after 1m0s
[13:11:22.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:11:0)
[13:11:22.498] Timer tick, asking Buildbucket for the build status
[13:11:22.680] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:22.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:12:0) after 1m0s
[13:12:22.855] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:12:0)
[13:12:22.855] Timer tick, asking Buildbucket for the build status
[13:12:23.134] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:23.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:13:0) after 1m0s
[13:13:23.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:13:0)
[13:13:23.230] Timer tick, asking Buildbucket for the build status
[13:13:23.321] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:23.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:14:0) after 1m0s
[13:14:23.352] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:14:0)
[13:14:23.352] Timer tick, asking Buildbucket for the build status
[13:14:23.434] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:23.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:15:0) after 1m0s
[13:15:23.449] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:15:0)
[13:15:23.449] Timer tick, asking Buildbucket for the build status
[13:15:23.529] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:23.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:16:0) after 1m0s
[13:16:23.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:16:0)
[13:16:23.647] Timer tick, asking Buildbucket for the build status
[13:16:23.736] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:23.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:17:0) after 1m0s
[13:17:24.483] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:17:0)
[13:17:24.483] Timer tick, asking Buildbucket for the build status
[13:17:24.631] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:24.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:18:0) after 1m0s
[13:18:25.444] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:18:0)
[13:18:25.444] Timer tick, asking Buildbucket for the build status
[13:18:25.649] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:25.649] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:19:0) after 1m0s
[13:19:25.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:19:0)
[13:19:25.854] Timer tick, asking Buildbucket for the build status
[13:19:26.104] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:26.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:20:0) after 1m0s
[13:20:26.269] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:20:0)
[13:20:26.269] Timer tick, asking Buildbucket for the build status
[13:20:26.533] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:26.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:21:0) after 1m0s
[13:21:26.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:21:0)
[13:21:26.632] Timer tick, asking Buildbucket for the build status
[13:21:26.784] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:26.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:22:0) after 1m0s
[13:22:26.891] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:22:0)
[13:22:26.892] Timer tick, asking Buildbucket for the build status
[13:22:27.076] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:27.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:23:0) after 1m0s
[13:23:27.231] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:23:0)
[13:23:27.231] Timer tick, asking Buildbucket for the build status
[13:23:27.481] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:27.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:24:0) after 1m0s
[13:24:27.524] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:24:0)
[13:24:27.524] Timer tick, asking Buildbucket for the build status
[13:24:27.630] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:27.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:25:0) after 1m0s
[13:25:27.773] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:25:0)
[13:25:27.773] Timer tick, asking Buildbucket for the build status
[13:25:27.933] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:27.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:26:0) after 1m0s
[13:26:27.967] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:26:0)
[13:26:27.967] Timer tick, asking Buildbucket for the build status
[13:26:28.105] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:28.105] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:27:0) after 1m0s
[13:27:28.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:27:0)
[13:27:28.176] Timer tick, asking Buildbucket for the build status
[13:27:28.838] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:28.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:28:0) after 1m0s
[13:28:28.906] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:28:0)
[13:28:28.906] Timer tick, asking Buildbucket for the build status
[13:28:29.351] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:29.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:29:0) after 1m0s
[13:29:29.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:29:0)
[13:29:29.348] Timer tick, asking Buildbucket for the build status
[13:29:29.457] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:29.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:30:0) after 1m0s
[13:30:29.571] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:30:0)
[13:30:29.571] Timer tick, asking Buildbucket for the build status
[13:30:29.662] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:29.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:31:0) after 1m0s
[13:31:29.688] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:31:0)
[13:31:29.688] Timer tick, asking Buildbucket for the build status
[13:31:29.783] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:29.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:32:0) after 1m0s
[13:32:29.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:32:0)
[13:32:29.839] Timer tick, asking Buildbucket for the build status
[13:32:29.984] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:29.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:33:0) after 1m0s
[13:33:30.171] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:33:0)
[13:33:30.171] Timer tick, asking Buildbucket for the build status
[13:33:30.267] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:30.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:34:0) after 1m0s
[13:34:30.290] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:34:0)
[13:34:30.290] Timer tick, asking Buildbucket for the build status
[13:34:31.310] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:31.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:35:0) after 1m0s
[13:35:31.514] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:35:0)
[13:35:31.514] Timer tick, asking Buildbucket for the build status
[13:35:31.579] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:31.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:36:0) after 1m0s
[13:36:31.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:36:0)
[13:36:31.633] Timer tick, asking Buildbucket for the build status
[13:36:31.724] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:31.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:37:0) after 1m0s
[13:37:31.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:37:0)
[13:37:31.830] Timer tick, asking Buildbucket for the build status
[13:37:31.946] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:31.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:38:0) after 1m0s
[13:38:32.093] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:38:0)
[13:38:32.093] Timer tick, asking Buildbucket for the build status
[13:38:32.491] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:32.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:39:0) after 1m0s
[13:39:32.614] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:39:0)
[13:39:32.614] Timer tick, asking Buildbucket for the build status
[13:39:32.692] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:32.692] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:40:0) after 1m0s
[13:40:32.839] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:40:0)
[13:40:32.839] Timer tick, asking Buildbucket for the build status
[13:40:32.941] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:32.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:41:0) after 1m0s
[13:41:32.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:41:0)
[13:41:32.997] Timer tick, asking Buildbucket for the build status
[13:41:33.310] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:33.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:42:0) after 1m0s
[13:42:33.369] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:42:0)
[13:42:33.369] Timer tick, asking Buildbucket for the build status
[13:42:33.569] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:33.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:43:0) after 1m0s
[13:43:33.581] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:43:0)
[13:43:33.581] Timer tick, asking Buildbucket for the build status
[13:43:33.988] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:33.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:44:0) after 1m0s
[13:44:34.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:44:0)
[13:44:34.034] Timer tick, asking Buildbucket for the build status
[13:44:34.199] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:34.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:45:0) after 1m0s
[13:45:34.217] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:45:0)
[13:45:34.217] Timer tick, asking Buildbucket for the build status
[13:45:34.542] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:34.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:46:0) after 1m0s
[13:46:34.678] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:46:0)
[13:46:34.678] Timer tick, asking Buildbucket for the build status
[13:46:34.883] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:34.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:47:0) after 1m0s
[13:47:34.919] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:47:0)
[13:47:34.919] Timer tick, asking Buildbucket for the build status
[13:47:35.309] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:35.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:48:0) after 1m0s
[13:48:35.324] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:48:0)
[13:48:35.324] Timer tick, asking Buildbucket for the build status
[13:48:35.384] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:35.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:49:0) after 1m0s
[13:49:35.409] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:49:0)
[13:49:35.409] Timer tick, asking Buildbucket for the build status
[13:49:35.500] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:35.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:50:0) after 1m0s
[13:50:35.575] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:50:0)
[13:50:35.575] Timer tick, asking Buildbucket for the build status
[13:50:35.675] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:35.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:51:0) after 1m0s
[13:51:35.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:51:0)
[13:51:35.768] Timer tick, asking Buildbucket for the build status
[13:51:35.859] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:35.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:52:0) after 1m0s
[13:52:35.886] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:52:0)
[13:52:35.886] Timer tick, asking Buildbucket for the build status
[13:52:36.251] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:36.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:53:0) after 1m0s
[13:53:36.397] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:53:0)
[13:53:36.397] Timer tick, asking Buildbucket for the build status
[13:53:36.461] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:36.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:54:0) after 1m0s
[13:54:36.507] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:54:0)
[13:54:36.507] Timer tick, asking Buildbucket for the build status
[13:54:36.624] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:36.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:55:0) after 1m0s
[13:55:36.699] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:55:0)
[13:55:36.699] Timer tick, asking Buildbucket for the build status
[13:55:37.679] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:37.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:56:0) after 1m0s
[13:56:37.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:56:0)
[13:56:37.803] Timer tick, asking Buildbucket for the build status
[13:56:38.046] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:38.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:57:0) after 1m0s
[13:57:38.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:57:0)
[13:57:38.038] Timer tick, asking Buildbucket for the build status
[13:57:38.198] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:38.198] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:58:0) after 1m0s
[13:58:38.233] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:58:0)
[13:58:38.233] Timer tick, asking Buildbucket for the build status
[13:58:38.357] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:38.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:59:0) after 1m0s
[13:59:38.443] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:59:0)
[13:59:38.443] Timer tick, asking Buildbucket for the build status
[13:59:38.530] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:38.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:60:0) after 1m0s
[14:00:38.643] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:60:0)
[14:00:38.643] Timer tick, asking Buildbucket for the build status
[14:00:38.875] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:38.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:61:0) after 1m0s
[14:01:39.118] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:61:0)
[14:01:39.118] Timer tick, asking Buildbucket for the build status
[14:01:39.248] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:39.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:62:0) after 1m0s
[14:02:39.272] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:62:0)
[14:02:39.272] Timer tick, asking Buildbucket for the build status
[14:02:39.423] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:39.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:63:0) after 1m0s
[14:03:40.400] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:63:0)
[14:03:40.400] Timer tick, asking Buildbucket for the build status
[14:03:40.485] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:40.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:64:0) after 1m0s
[14:04:40.637] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:64:0)
[14:04:40.637] Timer tick, asking Buildbucket for the build status
[14:04:40.890] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:40.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:65:0) after 1m0s
[14:05:41.038] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:65:0)
[14:05:41.038] Timer tick, asking Buildbucket for the build status
[14:05:41.530] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:41.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:66:0) after 1m0s
[14:06:41.578] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:66:0)
[14:06:41.578] Timer tick, asking Buildbucket for the build status
[14:06:41.664] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:41.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:67:0) after 1m0s
[14:07:41.639] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:67:0)
[14:07:41.639] Timer tick, asking Buildbucket for the build status
[14:07:41.817] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:41.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:68:0) after 1m0s
[14:08:41.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:68:0)
[14:08:41.873] Timer tick, asking Buildbucket for the build status
[14:08:41.962] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:41.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:69:0) after 1m0s
[14:09:42.075] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:69:0)
[14:09:42.075] Timer tick, asking Buildbucket for the build status
[14:09:42.148] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:42.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:70:0) after 1m0s
[14:10:42.196] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:70:0)
[14:10:42.196] Timer tick, asking Buildbucket for the build status
[14:10:42.284] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:42.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:71:0) after 1m0s
[14:11:42.346] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:71:0)
[14:11:42.346] Timer tick, asking Buildbucket for the build status
[14:11:42.430] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:42.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:72:0) after 1m0s
[14:12:42.499] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:72:0)
[14:12:42.499] Timer tick, asking Buildbucket for the build status
[14:12:42.613] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:42.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:73:0) after 1m0s
[14:13:42.654] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:73:0)
[14:13:42.654] Timer tick, asking Buildbucket for the build status
[14:13:42.755] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:42.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:74:0) after 1m0s
[14:14:42.816] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:74:0)
[14:14:42.816] Timer tick, asking Buildbucket for the build status
[14:14:43.017] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:43.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:75:0) after 1m0s
[14:15:43.049] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:75:0)
[14:15:43.049] Timer tick, asking Buildbucket for the build status
[14:15:43.143] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:43.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:76:0) after 1m0s
[14:16:43.172] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:76:0)
[14:16:43.172] Timer tick, asking Buildbucket for the build status
[14:16:43.271] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:43.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:77:0) after 1m0s
[14:17:43.568] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:77:0)
[14:17:43.568] Timer tick, asking Buildbucket for the build status
[14:17:43.704] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:43.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:78:0) after 1m0s
[14:18:43.737] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:78:0)
[14:18:43.737] Timer tick, asking Buildbucket for the build status
[14:18:43.829] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:43.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:79:0) after 1m0s
[14:19:43.872] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:79:0)
[14:19:43.872] Timer tick, asking Buildbucket for the build status
[14:19:44.003] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:44.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:80:0) after 1m0s
[14:20:44.088] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:80:0)
[14:20:44.088] Timer tick, asking Buildbucket for the build status
[14:20:44.449] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:44.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:81:0) after 1m0s
[14:21:44.479] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:81:0)
[14:21:44.480] Timer tick, asking Buildbucket for the build status
[14:21:44.715] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:44.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:82:0) after 1m0s
[14:22:44.746] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:82:0)
[14:22:44.746] Timer tick, asking Buildbucket for the build status
[14:22:44.963] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:44.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:83:0) after 1m0s
[14:23:44.996] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:83:0)
[14:23:44.996] Timer tick, asking Buildbucket for the build status
[14:23:45.260] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:45.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:84:0) after 1m0s
[14:24:45.303] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:84:0)
[14:24:45.303] Timer tick, asking Buildbucket for the build status
[14:24:45.500] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:45.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:85:0) after 1m0s
[14:25:45.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:85:0)
[14:25:45.612] Timer tick, asking Buildbucket for the build status
[14:25:45.822] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:45.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:86:0) after 1m0s
[14:26:45.841] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:86:0)
[14:26:45.841] Timer tick, asking Buildbucket for the build status
[14:26:47.001] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:47.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:87:0) after 1m0s
[14:27:47.037] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:87:0)
[14:27:47.037] Timer tick, asking Buildbucket for the build status
[14:27:47.444] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:47.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:88:0) after 1m0s
[14:28:47.434] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:88:0)
[14:28:47.434] Timer tick, asking Buildbucket for the build status
[14:28:47.544] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:47.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:89:0) after 1m0s
[14:29:47.607] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:89:0)
[14:29:47.607] Timer tick, asking Buildbucket for the build status
[14:29:47.664] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:47.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:90:0) after 1m0s
[14:30:47.719] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:90:0)
[14:30:47.719] Timer tick, asking Buildbucket for the build status
[14:30:47.830] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:47.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:91:0) after 1m0s
[14:31:47.900] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:91:0)
[14:31:47.900] Timer tick, asking Buildbucket for the build status
[14:31:47.979] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:47.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:92:0) after 1m0s
[14:32:48.015] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:92:0)
[14:32:48.015] Timer tick, asking Buildbucket for the build status
[14:32:48.179] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:48.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:93:0) after 1m0s
[14:33:48.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:93:0)
[14:33:48.230] Timer tick, asking Buildbucket for the build status
[14:33:48.515] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:48.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:94:0) after 1m0s
[14:34:48.561] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:94:0)
[14:34:48.561] Timer tick, asking Buildbucket for the build status
[14:34:48.799] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:48.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:95:0) after 1m0s
[14:35:48.823] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:95:0)
[14:35:48.823] Timer tick, asking Buildbucket for the build status
[14:35:49.037] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:49.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:96:0) after 1m0s
[14:36:49.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:96:0)
[14:36:49.028] Timer tick, asking Buildbucket for the build status
[14:36:49.314] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:49.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:97:0) after 1m0s
[14:37:49.344] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:97:0)
[14:37:49.344] Timer tick, asking Buildbucket for the build status
[14:37:49.549] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:49.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:98:0) after 1m0s
[14:38:49.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:98:0)
[14:38:49.596] Timer tick, asking Buildbucket for the build status
[14:38:49.693] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:49.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:99:0) after 1m0s
[14:39:49.775] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:99:0)
[14:39:49.775] Timer tick, asking Buildbucket for the build status
[14:39:49.984] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:49.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:100:0) after 1m0s
[14:40:50.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:100:0)
[14:40:50.034] Timer tick, asking Buildbucket for the build status
[14:40:50.205] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:50.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:101:0) after 1m0s
[14:41:50.237] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:101:0)
[14:41:50.237] Timer tick, asking Buildbucket for the build status
[14:41:50.990] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:50.990] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:102:0) after 1m0s
[14:42:51.089] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:102:0)
[14:42:51.089] Timer tick, asking Buildbucket for the build status
[14:42:51.189] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:51.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:103:0) after 1m0s
[14:43:51.222] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:103:0)
[14:43:51.222] Timer tick, asking Buildbucket for the build status
[14:43:51.332] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:51.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:104:0) after 1m0s
[14:44:51.383] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:104:0)
[14:44:51.383] Timer tick, asking Buildbucket for the build status
[14:44:51.592] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:51.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:105:0) after 1m0s
[14:45:51.647] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:105:0)
[14:45:51.647] Timer tick, asking Buildbucket for the build status
[14:45:51.724] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:51.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:106:0) after 1m0s
[14:46:51.817] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:106:0)
[14:46:51.817] Timer tick, asking Buildbucket for the build status
[14:46:51.924] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:51.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:107:0) after 1m0s
[14:47:51.983] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:107:0)
[14:47:51.983] Timer tick, asking Buildbucket for the build status
[14:47:52.461] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:52.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:108:0) after 1m0s
[14:48:52.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:108:0)
[14:48:52.493] Timer tick, asking Buildbucket for the build status
[14:48:52.741] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:52.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:109:0) after 1m0s
[14:49:52.767] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:109:0)
[14:49:52.767] Timer tick, asking Buildbucket for the build status
[14:49:53.019] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:53.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:110:0) after 1m0s
[14:50:53.107] Handling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:110:0)
[14:50:53.107] Timer tick, asking Buildbucket for the build status
[14:50:53.324] Build 8872168976108518016: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:53.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9037599038385898576:111:0) after 1m0s
[14:51:03.797] Received PubSub notification, asking Buildbucket for the build status
[14:51:03.941] Build 8872168976108518016: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:51:03.941] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1597243862678041",
"created_by": "project:chromium",
"created_ts": "1597237338876298",
"id": "8872168976108518016",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac\"}}",
"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-4-74dv\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 323, \"got_angle_revision\": \"9c37bbd3e5ebeba57604d42d17d0199f1b271d6b\", \"got_dawn_revision\": \"1aff02d444786a451f72a9b3bb5d064f0b26e0f4\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac\", \"got_revision_cp\": \"refs/heads/master@{#797211}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"13f04d59c7278ac87191465bc16e2c7d145ae851\", \"got_v8_revision\": \"689a3a9bbbc57f228076de6d891caacd77118f86\", \"got_v8_revision_cp\": \"refs/heads/8.6.355@{#1}\", \"got_webrtc_revision\": \"c2cfd18ab8cabb29fe0866d467588d22b8c5e501\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31914}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac\"}, \"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-4-74dv\"], \"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\": [\"5480-a98aa12\"], \"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": "1597237342934025",
"status": "COMPLETED",
"status_changed_ts": "1597243863216725",
"tags": [
"build_address:luci.chromium.ci/win-official/323",
"builder:win-official",
"buildset:commit/git/dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dcd71cdf4417f56a9b8effc66c851d12f4b0b3ac",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9037599038385898576",
"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/8872168976108518016/+/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:4dfb95327d4add10",
"user_agent:luci-scheduler"
],
"updated_ts": "1597243863373309",
"url": "https://ci.chromium.org/b/8872168976108518016",
"utcnow_ts": "1597243863921828"
}
}
[14:51:03.941] Invocation finished in 1h48m46.619606432s with status SUCCEEDED