[12:18:24.473] New invocation is queued and will start shortly
[12:18:25.597] Starting the invocation (attempt 1)
[12:18:25.636] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:18:25.636] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:18:25.636] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9038235977911153184",
"parameters_json": "{\"builder_name\":\"win-official\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a0d031f9046a9c984926caecea37248929727589\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-official",
"scheduler_invocation_id:9038235977911153184",
"scheduler_job_id:chromium/win-official",
"user_agent:luci-scheduler",
"buildset:commit/git/a0d031f9046a9c984926caecea37248929727589",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0d031f9046a9c984926caecea37248929727589",
"gitiles_ref:refs/heads/master"
]
}
[12:18:27.373] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1596629905720052",
"id": "8872805915937579856",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a0d031f9046a9c984926caecea37248929727589\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1596629906157812",
"tags": [
"build_address:luci.chromium.ci/win-official/262",
"builder:win-official",
"buildset:commit/git/a0d031f9046a9c984926caecea37248929727589",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0d031f9046a9c984926caecea37248929727589",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038235977911153184",
"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/8872805915937579856/+/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": "1596629906158046",
"url": "https://ci.chromium.org/b/8872805915937579856",
"utcnow_ts": "1596629907363582"
}
}
[12:18:27.373] Task URL: https://ci.chromium.org/b/8872805915937579856
[12:18:27.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:2:0) after 1m0s
[12:18:28.367] Received PubSub notification, asking Buildbucket for the build status
[12:18:28.532] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:27.422] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:2:0)
[12:19:27.422] Timer tick, asking Buildbucket for the build status
[12:19:27.574] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:27.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:4:0) after 1m0s
[12:20:27.584] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:4:0)
[12:20:27.584] Timer tick, asking Buildbucket for the build status
[12:20:27.734] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:27.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:5:0) after 1m0s
[12:21:27.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:5:0)
[12:21:27.725] Timer tick, asking Buildbucket for the build status
[12:21:27.834] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:27.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:6:0) after 1m0s
[12:22:27.873] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:6:0)
[12:22:27.873] Timer tick, asking Buildbucket for the build status
[12:22:27.967] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:27.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:7:0) after 1m0s
[12:23:27.999] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:7:0)
[12:23:27.999] Timer tick, asking Buildbucket for the build status
[12:23:28.290] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:28.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:8:0) after 1m0s
[12:24:28.327] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:8:0)
[12:24:28.327] Timer tick, asking Buildbucket for the build status
[12:24:28.579] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:28.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:9:0) after 1m0s
[12:25:28.662] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:9:0)
[12:25:28.662] Timer tick, asking Buildbucket for the build status
[12:25:28.750] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:28.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:10:0) after 1m0s
[12:26:28.818] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:10:0)
[12:26:28.818] Timer tick, asking Buildbucket for the build status
[12:26:28.905] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:28.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:11:0) after 1m0s
[12:27:28.936] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:11:0)
[12:27:28.936] Timer tick, asking Buildbucket for the build status
[12:27:29.107] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:29.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:12:0) after 1m0s
[12:28:29.214] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:12:0)
[12:28:29.214] Timer tick, asking Buildbucket for the build status
[12:28:29.343] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:29.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:13:0) after 1m0s
[12:29:29.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:13:0)
[12:29:29.451] Timer tick, asking Buildbucket for the build status
[12:29:29.930] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:29.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:14:0) after 1m0s
[12:30:30.057] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:14:0)
[12:30:30.057] Timer tick, asking Buildbucket for the build status
[12:30:30.341] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:30.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:15:0) after 1m0s
[12:31:30.374] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:15:0)
[12:31:30.374] Timer tick, asking Buildbucket for the build status
[12:31:30.598] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:30.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:16:0) after 1m0s
[12:32:30.629] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:16:0)
[12:32:30.629] Timer tick, asking Buildbucket for the build status
[12:32:30.756] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:30.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:17:0) after 1m0s
[12:33:30.803] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:17:0)
[12:33:30.803] Timer tick, asking Buildbucket for the build status
[12:33:30.976] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:30.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:18:0) after 1m0s
[12:34:31.045] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:18:0)
[12:34:31.045] Timer tick, asking Buildbucket for the build status
[12:34:31.220] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:31.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:19:0) after 1m0s
[12:35:31.239] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:19:0)
[12:35:31.239] Timer tick, asking Buildbucket for the build status
[12:35:31.438] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:31.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:20:0) after 1m0s
[12:36:31.451] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:20:0)
[12:36:31.451] Timer tick, asking Buildbucket for the build status
[12:36:31.772] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:31.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:21:0) after 1m0s
[12:37:31.794] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:21:0)
[12:37:31.794] Timer tick, asking Buildbucket for the build status
[12:37:32.200] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:32.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:22:0) after 1m0s
[12:38:32.273] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:22:0)
[12:38:32.273] Timer tick, asking Buildbucket for the build status
[12:38:32.403] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:32.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:23:0) after 1m0s
[12:39:32.484] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:23:0)
[12:39:32.484] Timer tick, asking Buildbucket for the build status
[12:39:32.565] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:32.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:24:0) after 1m0s
[12:40:32.596] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:24:0)
[12:40:32.596] Timer tick, asking Buildbucket for the build status
[12:40:32.676] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:32.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:25:0) after 1m0s
[12:41:32.704] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:25:0)
[12:41:32.704] Timer tick, asking Buildbucket for the build status
[12:41:32.776] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:32.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:26:0) after 1m0s
[12:42:32.813] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:26:0)
[12:42:32.813] Timer tick, asking Buildbucket for the build status
[12:42:32.905] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:32.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:27:0) after 1m0s
[12:43:32.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:27:0)
[12:43:32.933] Timer tick, asking Buildbucket for the build status
[12:43:33.701] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:33.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:28:0) after 1m0s
[12:44:33.752] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:28:0)
[12:44:33.752] Timer tick, asking Buildbucket for the build status
[12:44:33.865] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:33.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:29:0) after 1m0s
[12:45:33.890] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:29:0)
[12:45:33.890] Timer tick, asking Buildbucket for the build status
[12:45:34.011] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:34.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:30:0) after 1m0s
[12:46:34.041] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:30:0)
[12:46:34.041] Timer tick, asking Buildbucket for the build status
[12:46:34.222] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:34.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:31:0) after 1m0s
[12:47:34.313] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:31:0)
[12:47:34.313] Timer tick, asking Buildbucket for the build status
[12:47:34.514] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:34.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:32:0) after 1m0s
[12:48:34.616] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:32:0)
[12:48:34.616] Timer tick, asking Buildbucket for the build status
[12:48:34.770] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:34.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:33:0) after 1m0s
[12:49:34.912] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:33:0)
[12:49:34.912] Timer tick, asking Buildbucket for the build status
[12:49:35.009] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:35.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:34:0) after 1m0s
[12:50:35.108] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:34:0)
[12:50:35.108] Timer tick, asking Buildbucket for the build status
[12:50:35.192] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:35.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:35:0) after 1m0s
[12:51:35.318] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:35:0)
[12:51:35.318] Timer tick, asking Buildbucket for the build status
[12:51:35.397] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:35.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:36:0) after 1m0s
[12:52:35.456] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:36:0)
[12:52:35.456] Timer tick, asking Buildbucket for the build status
[12:52:35.531] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:35.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:37:0) after 1m0s
[12:53:35.591] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:37:0)
[12:53:35.591] Timer tick, asking Buildbucket for the build status
[12:53:36.042] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:36.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:38:0) after 1m0s
[12:54:36.212] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:38:0)
[12:54:36.212] Timer tick, asking Buildbucket for the build status
[12:54:36.287] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:36.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:39:0) after 1m0s
[12:55:36.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:39:0)
[12:55:36.403] Timer tick, asking Buildbucket for the build status
[12:55:36.555] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:36.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:40:0) after 1m0s
[12:56:36.633] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:40:0)
[12:56:36.633] Timer tick, asking Buildbucket for the build status
[12:56:36.719] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:36.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:41:0) after 1m0s
[12:57:36.729] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:41:0)
[12:57:36.729] Timer tick, asking Buildbucket for the build status
[12:57:37.087] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:37.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:42:0) after 1m0s
[12:58:37.227] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:42:0)
[12:58:37.227] Timer tick, asking Buildbucket for the build status
[12:58:37.987] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:37.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:43:0) after 1m0s
[12:59:38.047] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:43:0)
[12:59:38.047] Timer tick, asking Buildbucket for the build status
[12:59:38.175] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:38.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:44:0) after 1m0s
[13:00:38.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:44:0)
[13:00:38.230] Timer tick, asking Buildbucket for the build status
[13:00:38.307] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:38.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:45:0) after 1m0s
[13:01:38.348] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:45:0)
[13:01:38.348] Timer tick, asking Buildbucket for the build status
[13:01:38.541] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:38.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:46:0) after 1m0s
[13:02:38.583] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:46:0)
[13:02:38.583] Timer tick, asking Buildbucket for the build status
[13:02:38.707] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:38.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:47:0) after 1m0s
[13:03:38.774] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:47:0)
[13:03:38.774] Timer tick, asking Buildbucket for the build status
[13:03:38.926] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:38.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:48:0) after 1m0s
[13:04:39.007] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:48:0)
[13:04:39.007] Timer tick, asking Buildbucket for the build status
[13:04:39.240] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:39.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:49:0) after 1m0s
[13:05:39.280] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:49:0)
[13:05:39.280] Timer tick, asking Buildbucket for the build status
[13:05:39.366] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:39.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:50:0) after 1m0s
[13:06:39.474] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:50:0)
[13:06:39.474] Timer tick, asking Buildbucket for the build status
[13:06:39.544] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:39.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:51:0) after 1m0s
[13:07:39.625] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:51:0)
[13:07:39.625] Timer tick, asking Buildbucket for the build status
[13:07:39.710] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:39.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:52:0) after 1m0s
[13:08:39.780] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:52:0)
[13:08:39.780] Timer tick, asking Buildbucket for the build status
[13:08:39.969] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:40.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:53:0) after 1m0s
[13:09:40.072] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:53:0)
[13:09:40.072] Timer tick, asking Buildbucket for the build status
[13:09:40.217] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:40.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:54:0) after 1m0s
[13:10:40.268] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:54:0)
[13:10:40.268] Timer tick, asking Buildbucket for the build status
[13:10:40.529] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:40.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:55:0) after 1m0s
[13:11:40.539] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:55:0)
[13:11:40.539] Timer tick, asking Buildbucket for the build status
[13:11:40.621] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:40.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:56:0) after 1m0s
[13:12:40.687] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:56:0)
[13:12:40.687] Timer tick, asking Buildbucket for the build status
[13:12:40.789] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:40.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:57:0) after 1m0s
[13:13:40.804] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:57:0)
[13:13:40.804] Timer tick, asking Buildbucket for the build status
[13:13:40.959] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:40.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:58:0) after 1m0s
[13:14:41.034] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:58:0)
[13:14:41.034] Timer tick, asking Buildbucket for the build status
[13:14:41.119] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:41.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:59:0) after 1m0s
[13:15:41.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:59:0)
[13:15:41.201] Timer tick, asking Buildbucket for the build status
[13:15:41.280] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:41.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:60:0) after 1m0s
[13:16:41.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:60:0)
[13:16:41.283] Timer tick, asking Buildbucket for the build status
[13:16:41.646] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:41.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:61:0) after 1m0s
[13:17:41.725] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:61:0)
[13:17:41.725] Timer tick, asking Buildbucket for the build status
[13:17:41.906] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:41.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:62:0) after 1m0s
[13:18:41.940] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:62:0)
[13:18:41.940] Timer tick, asking Buildbucket for the build status
[13:18:42.088] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:42.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:63:0) after 1m0s
[13:19:42.063] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:63:0)
[13:19:42.063] Timer tick, asking Buildbucket for the build status
[13:19:42.127] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:42.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:64:0) after 1m0s
[13:20:42.230] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:64:0)
[13:20:42.230] Timer tick, asking Buildbucket for the build status
[13:20:43.821] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:43.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:65:0) after 1m0s
[13:21:43.860] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:65:0)
[13:21:43.860] Timer tick, asking Buildbucket for the build status
[13:21:44.068] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:44.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:66:0) after 1m0s
[13:22:44.176] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:66:0)
[13:22:44.176] Timer tick, asking Buildbucket for the build status
[13:22:44.274] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:44.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:67:0) after 1m0s
[13:23:44.395] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:67:0)
[13:23:44.395] Timer tick, asking Buildbucket for the build status
[13:23:44.926] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:44.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:68:0) after 1m0s
[13:24:45.039] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:68:0)
[13:24:45.039] Timer tick, asking Buildbucket for the build status
[13:24:45.201] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:45.201] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:69:0) after 1m0s
[13:25:45.241] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:69:0)
[13:25:45.241] Timer tick, asking Buildbucket for the build status
[13:25:45.592] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:45.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:70:0) after 1m0s
[13:26:46.412] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:70:0)
[13:26:46.412] Timer tick, asking Buildbucket for the build status
[13:26:46.550] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:46.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:71:0) after 1m0s
[13:27:46.585] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:71:0)
[13:27:46.585] Timer tick, asking Buildbucket for the build status
[13:27:46.679] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:46.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:72:0) after 1m0s
[13:28:46.745] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:72:0)
[13:28:46.745] Timer tick, asking Buildbucket for the build status
[13:28:46.891] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:46.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:73:0) after 1m0s
[13:29:46.945] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:73:0)
[13:29:46.945] Timer tick, asking Buildbucket for the build status
[13:29:47.019] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:47.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:74:0) after 1m0s
[13:30:47.052] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:74:0)
[13:30:47.052] Timer tick, asking Buildbucket for the build status
[13:30:47.141] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:47.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:75:0) after 1m0s
[13:31:47.153] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:75:0)
[13:31:47.153] Timer tick, asking Buildbucket for the build status
[13:31:47.258] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:47.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:76:0) after 1m0s
[13:32:47.309] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:76:0)
[13:32:47.309] Timer tick, asking Buildbucket for the build status
[13:32:47.508] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:47.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:77:0) after 1m0s
[13:33:47.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:77:0)
[13:33:47.537] Timer tick, asking Buildbucket for the build status
[13:33:47.667] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:47.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:78:0) after 1m0s
[13:34:47.693] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:78:0)
[13:34:47.693] Timer tick, asking Buildbucket for the build status
[13:34:47.758] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:47.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:79:0) after 1m0s
[13:35:47.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:79:0)
[13:35:47.832] Timer tick, asking Buildbucket for the build status
[13:35:47.894] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:47.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:80:0) after 1m0s
[13:36:47.955] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:80:0)
[13:36:47.955] Timer tick, asking Buildbucket for the build status
[13:36:48.032] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:48.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:81:0) after 1m0s
[13:37:48.065] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:81:0)
[13:37:48.065] Timer tick, asking Buildbucket for the build status
[13:37:48.144] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:48.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:82:0) after 1m0s
[13:38:48.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:82:0)
[13:38:48.216] Timer tick, asking Buildbucket for the build status
[13:38:48.407] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:48.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:83:0) after 1m0s
[13:39:48.511] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:83:0)
[13:39:48.511] Timer tick, asking Buildbucket for the build status
[13:39:48.708] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:48.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:84:0) after 1m0s
[13:40:48.832] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:84:0)
[13:40:48.832] Timer tick, asking Buildbucket for the build status
[13:40:48.936] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:48.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:85:0) after 1m0s
[13:41:48.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:85:0)
[13:41:48.982] Timer tick, asking Buildbucket for the build status
[13:41:51.734] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:51.734] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:86:0) after 1m0s
[13:42:51.768] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:86:0)
[13:42:51.768] Timer tick, asking Buildbucket for the build status
[13:42:51.843] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:51.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:87:0) after 1m0s
[13:43:51.885] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:87:0)
[13:43:51.885] Timer tick, asking Buildbucket for the build status
[13:43:52.079] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:52.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:88:0) after 1m0s
[13:44:52.200] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:88:0)
[13:44:52.200] Timer tick, asking Buildbucket for the build status
[13:44:52.283] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:52.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:89:0) after 1m0s
[13:45:52.354] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:89:0)
[13:45:52.354] Timer tick, asking Buildbucket for the build status
[13:45:52.452] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:52.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:90:0) after 1m0s
[13:46:52.505] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:90:0)
[13:46:52.505] Timer tick, asking Buildbucket for the build status
[13:46:52.805] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:52.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:91:0) after 1m0s
[13:47:53.028] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:91:0)
[13:47:53.028] Timer tick, asking Buildbucket for the build status
[13:47:53.385] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:53.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:92:0) after 1m0s
[13:48:53.473] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:92:0)
[13:48:53.473] Timer tick, asking Buildbucket for the build status
[13:48:53.993] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:53.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:93:0) after 1m0s
[13:49:53.993] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:93:0)
[13:49:53.993] Timer tick, asking Buildbucket for the build status
[13:49:54.068] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:54.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:94:0) after 1m0s
[13:50:54.104] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:94:0)
[13:50:54.104] Timer tick, asking Buildbucket for the build status
[13:50:54.177] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:54.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:95:0) after 1m0s
[13:51:54.216] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:95:0)
[13:51:54.216] Timer tick, asking Buildbucket for the build status
[13:51:54.387] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:54.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:96:0) after 1m0s
[13:52:54.563] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:96:0)
[13:52:54.563] Timer tick, asking Buildbucket for the build status
[13:52:54.657] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:54.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:97:0) after 1m0s
[13:53:54.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:97:0)
[13:53:54.894] Timer tick, asking Buildbucket for the build status
[13:53:56.500] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:56.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:98:0) after 1m0s
[13:54:56.612] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:98:0)
[13:54:56.612] Timer tick, asking Buildbucket for the build status
[13:54:56.962] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:56.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:99:0) after 1m0s
[13:55:58.477] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:99:0)
[13:55:58.477] Timer tick, asking Buildbucket for the build status
[13:55:58.539] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:58.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:100:0) after 1m0s
[13:56:58.602] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:100:0)
[13:56:58.602] Timer tick, asking Buildbucket for the build status
[13:56:58.816] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:58.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:101:0) after 1m0s
[13:57:58.878] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:101:0)
[13:57:58.878] Timer tick, asking Buildbucket for the build status
[13:57:58.944] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:58.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:102:0) after 1m0s
[13:58:59.124] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:102:0)
[13:58:59.124] Timer tick, asking Buildbucket for the build status
[13:58:59.391] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:59.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:103:0) after 1m0s
[13:59:59.429] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:103:0)
[13:59:59.429] Timer tick, asking Buildbucket for the build status
[13:59:59.518] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:59.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:104:0) after 1m0s
[14:00:59.692] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:104:0)
[14:00:59.692] Timer tick, asking Buildbucket for the build status
[14:00:59.792] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:59.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:105:0) after 1m0s
[14:01:59.866] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:105:0)
[14:01:59.866] Timer tick, asking Buildbucket for the build status
[14:02:00.148] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:00.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:106:0) after 1m0s
[14:03:00.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:106:0)
[14:03:00.178] Timer tick, asking Buildbucket for the build status
[14:03:00.252] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:00.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:107:0) after 1m0s
[14:04:02.135] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:107:0)
[14:04:02.135] Timer tick, asking Buildbucket for the build status
[14:04:02.346] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:02.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:108:0) after 1m0s
[14:05:02.430] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:108:0)
[14:05:02.430] Timer tick, asking Buildbucket for the build status
[14:05:02.519] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:02.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:109:0) after 1m0s
[14:06:02.613] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:109:0)
[14:06:02.613] Timer tick, asking Buildbucket for the build status
[14:06:02.745] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:06:02.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:110:0) after 1m0s
[14:07:02.861] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:110:0)
[14:07:02.861] Timer tick, asking Buildbucket for the build status
[14:07:02.960] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:02.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:111:0) after 1m0s
[14:08:03.120] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:111:0)
[14:08:03.120] Timer tick, asking Buildbucket for the build status
[14:08:03.238] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:03.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:112:0) after 1m0s
[14:09:03.274] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:112:0)
[14:09:03.274] Timer tick, asking Buildbucket for the build status
[14:09:03.446] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:03.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:113:0) after 1m0s
[14:10:03.491] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:113:0)
[14:10:03.491] Timer tick, asking Buildbucket for the build status
[14:10:03.566] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:03.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:114:0) after 1m0s
[14:11:03.632] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:114:0)
[14:11:03.632] Timer tick, asking Buildbucket for the build status
[14:11:03.771] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:03.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:115:0) after 1m0s
[14:12:03.846] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:115:0)
[14:12:03.846] Timer tick, asking Buildbucket for the build status
[14:12:03.947] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:03.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:116:0) after 1m0s
[14:13:04.022] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:116:0)
[14:13:04.022] Timer tick, asking Buildbucket for the build status
[14:13:04.109] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:04.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:117:0) after 1m0s
[14:14:04.122] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:117:0)
[14:14:04.122] Timer tick, asking Buildbucket for the build status
[14:14:04.242] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:04.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:118:0) after 1m0s
[14:15:04.459] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:118:0)
[14:15:04.459] Timer tick, asking Buildbucket for the build status
[14:15:04.544] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:04.544] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:119:0) after 1m0s
[14:16:04.615] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:119:0)
[14:16:04.615] Timer tick, asking Buildbucket for the build status
[14:16:04.896] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:04.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:120:0) after 1m0s
[14:17:05.178] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:120:0)
[14:17:05.178] Timer tick, asking Buildbucket for the build status
[14:17:05.234] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:05.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:121:0) after 1m0s
[14:18:05.493] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:121:0)
[14:18:05.493] Timer tick, asking Buildbucket for the build status
[14:18:05.709] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:05.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:122:0) after 1m0s
[14:19:05.894] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:122:0)
[14:19:05.894] Timer tick, asking Buildbucket for the build status
[14:19:06.012] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:06.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:123:0) after 1m0s
[14:20:06.159] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:123:0)
[14:20:06.159] Timer tick, asking Buildbucket for the build status
[14:20:06.242] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:06.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:124:0) after 1m0s
[14:21:06.608] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:124:0)
[14:21:06.608] Timer tick, asking Buildbucket for the build status
[14:21:06.795] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:06.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:125:0) after 1m0s
[14:22:06.893] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:125:0)
[14:22:06.893] Timer tick, asking Buildbucket for the build status
[14:22:07.466] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:07.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:126:0) after 1m0s
[14:23:07.498] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:126:0)
[14:23:07.498] Timer tick, asking Buildbucket for the build status
[14:23:07.596] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:07.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:127:0) after 1m0s
[14:24:07.742] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:127:0)
[14:24:07.742] Timer tick, asking Buildbucket for the build status
[14:24:07.863] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:07.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:128:0) after 1m0s
[14:25:08.063] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:128:0)
[14:25:08.063] Timer tick, asking Buildbucket for the build status
[14:25:08.143] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:08.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:129:0) after 1m0s
[14:26:08.437] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:129:0)
[14:26:08.437] Timer tick, asking Buildbucket for the build status
[14:26:08.642] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:08.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:130:0) after 1m0s
[14:27:08.770] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:130:0)
[14:27:08.770] Timer tick, asking Buildbucket for the build status
[14:27:09.226] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:09.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:131:0) after 1m0s
[14:28:09.319] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:131:0)
[14:28:09.319] Timer tick, asking Buildbucket for the build status
[14:28:09.408] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:09.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:132:0) after 1m0s
[14:29:09.560] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:132:0)
[14:29:09.560] Timer tick, asking Buildbucket for the build status
[14:29:09.707] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:09.707] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:133:0) after 1m0s
[14:30:09.788] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:133:0)
[14:30:09.788] Timer tick, asking Buildbucket for the build status
[14:30:10.012] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:10.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:134:0) after 1m0s
[14:31:10.180] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:134:0)
[14:31:10.180] Timer tick, asking Buildbucket for the build status
[14:31:10.255] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:10.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:135:0) after 1m0s
[14:32:10.283] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:135:0)
[14:32:10.283] Timer tick, asking Buildbucket for the build status
[14:32:10.646] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:10.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:136:0) after 1m0s
[14:33:10.769] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:136:0)
[14:33:10.769] Timer tick, asking Buildbucket for the build status
[14:33:10.863] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:10.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:137:0) after 1m0s
[14:34:11.006] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:137:0)
[14:34:11.006] Timer tick, asking Buildbucket for the build status
[14:34:11.485] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:11.485] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:138:0) after 1m0s
[14:35:11.518] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:138:0)
[14:35:11.518] Timer tick, asking Buildbucket for the build status
[14:35:11.591] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:11.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:139:0) after 1m0s
[14:36:11.825] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:139:0)
[14:36:11.825] Timer tick, asking Buildbucket for the build status
[14:36:11.991] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:11.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:140:0) after 1m0s
[14:37:12.033] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:140:0)
[14:37:12.033] Timer tick, asking Buildbucket for the build status
[14:37:12.251] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:12.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:141:0) after 1m0s
[14:38:12.634] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:141:0)
[14:38:12.634] Timer tick, asking Buildbucket for the build status
[14:38:12.701] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:12.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:142:0) after 1m0s
[14:39:12.849] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:142:0)
[14:39:12.849] Timer tick, asking Buildbucket for the build status
[14:39:12.950] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:12.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:143:0) after 1m0s
[14:40:13.116] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:143:0)
[14:40:13.116] Timer tick, asking Buildbucket for the build status
[14:40:13.538] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:13.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:144:0) after 1m0s
[14:41:13.606] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:144:0)
[14:41:13.606] Timer tick, asking Buildbucket for the build status
[14:41:13.754] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:13.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:145:0) after 1m0s
[14:42:13.854] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:145:0)
[14:42:13.854] Timer tick, asking Buildbucket for the build status
[14:42:14.130] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:14.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:146:0) after 1m0s
[14:43:14.295] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:146:0)
[14:43:14.295] Timer tick, asking Buildbucket for the build status
[14:43:14.524] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:14.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:147:0) after 1m0s
[14:44:14.626] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:147:0)
[14:44:14.626] Timer tick, asking Buildbucket for the build status
[14:44:14.795] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:14.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:148:0) after 1m0s
[14:45:14.933] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:148:0)
[14:45:14.933] Timer tick, asking Buildbucket for the build status
[14:45:15.039] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:15.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:149:0) after 1m0s
[14:46:15.403] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:149:0)
[14:46:15.403] Timer tick, asking Buildbucket for the build status
[14:46:15.490] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:15.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:150:0) after 1m0s
[14:47:15.796] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:150:0)
[14:47:15.796] Timer tick, asking Buildbucket for the build status
[14:47:15.912] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:15.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:151:0) after 1m0s
[14:48:16.201] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:151:0)
[14:48:16.201] Timer tick, asking Buildbucket for the build status
[14:48:16.643] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:16.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:152:0) after 1m0s
[14:49:16.784] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:152:0)
[14:49:16.784] Timer tick, asking Buildbucket for the build status
[14:49:16.981] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:16.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:153:0) after 1m0s
[14:50:17.110] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:153:0)
[14:50:17.110] Timer tick, asking Buildbucket for the build status
[14:50:17.191] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:17.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:154:0) after 1m0s
[14:51:17.431] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:154:0)
[14:51:17.431] Timer tick, asking Buildbucket for the build status
[14:51:17.502] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:17.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:155:0) after 1m0s
[14:52:17.851] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:155:0)
[14:52:17.851] Timer tick, asking Buildbucket for the build status
[14:52:18.001] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:18.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:156:0) after 1m0s
[14:53:18.909] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:156:0)
[14:53:18.909] Timer tick, asking Buildbucket for the build status
[14:53:19.193] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:19.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:157:0) after 1m0s
[14:54:19.351] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:157:0)
[14:54:19.351] Timer tick, asking Buildbucket for the build status
[14:54:19.629] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:19.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:158:0) after 1m0s
[14:55:19.795] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:158:0)
[14:55:19.795] Timer tick, asking Buildbucket for the build status
[14:55:19.863] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:19.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:159:0) after 1m0s
[14:56:19.921] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:159:0)
[14:56:19.921] Timer tick, asking Buildbucket for the build status
[14:56:19.982] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:19.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:160:0) after 1m0s
[14:57:19.982] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:160:0)
[14:57:19.982] Timer tick, asking Buildbucket for the build status
[14:57:20.163] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:20.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:161:0) after 1m0s
[14:58:20.293] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:161:0)
[14:58:20.293] Timer tick, asking Buildbucket for the build status
[14:58:20.379] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:20.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:162:0) after 1m0s
[14:59:20.501] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:162:0)
[14:59:20.501] Timer tick, asking Buildbucket for the build status
[14:59:20.688] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:20.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:163:0) after 1m0s
[15:00:20.830] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:163:0)
[15:00:20.830] Timer tick, asking Buildbucket for the build status
[15:00:21.453] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:21.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:164:0) after 1m0s
[15:01:21.537] Handling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:164:0)
[15:01:21.537] Timer tick, asking Buildbucket for the build status
[15:01:21.878] Build 8872805915937579856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:21.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-official:9038235977911153184:165:0) after 1m0s
[15:02:19.249] Received PubSub notification, asking Buildbucket for the build status
[15:02:19.655] Build 8872805915937579856: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:02:19.655] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1596639738154601",
"created_by": "project:chromium",
"created_ts": "1596629905720052",
"id": "8872805915937579856",
"parameters_json": "{\"builder_name\": \"win-official\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a0d031f9046a9c984926caecea37248929727589\"}}",
"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/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"a0d031f9046a9c984926caecea37248929727589\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"win-dbg-6-w2va\", \"branch\": \"refs/heads/master\", \"buildername\": \"win-official\", \"buildnumber\": 262, \"got_angle_revision\": \"38ddebf9a771aef3a906667595354c7a564edf31\", \"got_dawn_revision\": \"076a4e58205dfa2bcf5e33cc8718aa4799d46735\", \"got_nacl_revision\": \"3008f9e6de122325d8f9dbf02f7cdd51fa1ec306\", \"got_revision\": \"a0d031f9046a9c984926caecea37248929727589\", \"got_revision_cp\": \"refs/heads/master@{#794979}\", \"got_swarming_client_revision\": \"4c095d04179dc725a300085ae21fe3b79900d072\", \"got_swiftshader_revision\": \"be7c55a2a8cebd46ba6912e6b7d4dae8353d154c\", \"got_v8_revision\": \"ce4c3060f6fbfd5ed410de314f7e83a66fd792d9\", \"got_v8_revision_cp\": \"refs/heads/8.6.334@{#1}\", \"got_webrtc_revision\": \"7e30909bf44a224f7dbb49fc6bac6f2fdd4fd822\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#31842}\", \"mastername\": \"chromium\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a0d031f9046a9c984926caecea37248929727589\"}, \"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-6-w2va\"], \"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\": [\"5436-adf7f8a\"], \"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": "1596629907356243",
"status": "COMPLETED",
"status_changed_ts": "1596639738863089",
"tags": [
"build_address:luci.chromium.ci/win-official/262",
"builder:win-official",
"buildset:commit/git/a0d031f9046a9c984926caecea37248929727589",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a0d031f9046a9c984926caecea37248929727589",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9038235977911153184",
"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/8872805915937579856/+/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:4dd7607d2e864010",
"user_agent:luci-scheduler"
],
"updated_ts": "1596639738894161",
"url": "https://ci.chromium.org/b/8872805915937579856",
"utcnow_ts": "1596639739639691"
}
}
[15:02:19.655] Invocation finished in 2h43m55.200171884s with status SUCCEEDED