[12:07:08.106] New invocation is queued and will start shortly
[12:07:09.251] Starting the invocation (attempt 1)
[12:07:09.298] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:07:09.298] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:07:09.298] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9085256512694169136",
"parameters_json": "{\"builder_name\":\"archive\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"7882a8141f85d4330d84b34a1448a5d531426066\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:archive",
"scheduler_invocation_id:9085256512694169136",
"scheduler_job_id:r8/archive",
"user_agent:luci-scheduler",
"buildset:commit/git/7882a8141f85d4330d84b34a1448a5d531426066",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/7882a8141f85d4330d84b34a1448a5d531426066",
"gitiles_ref:refs/heads/master"
]
}
[12:07:11.956] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551787629536216",
"id": "8919826450529704720",
"lease_expiration_ts": "1551897431495409",
"lease_key": "387598550",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"7882a8141f85d4330d84b34a1448a5d531426066\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551787631736661",
"tags": [
"build_address:luci.r8.ci/archive/49",
"builder:archive",
"buildset:commit/git/7882a8141f85d4330d84b34a1448a5d531426066",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/7882a8141f85d4330d84b34a1448a5d531426066",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085256512694169136",
"scheduler_job_id:r8/archive",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8919826450529704720/+/annotations",
"swarming_tag:luci_project:r8",
"swarming_tag:recipe_name:rex",
"swarming_tag:recipe_package:infra_internal/recipe_bundles/chrome-internal.googlesource.com/chrome/tools/build_limited/scripts/slave",
"swarming_task_id:436691aa312cde10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551787631736782",
"url": "https://ci.chromium.org/b/8919826450529704720",
"utcnow_ts": "1551787631941343"
}
}
[12:07:11.956] Task URL: https://ci.chromium.org/b/8919826450529704720
[12:07:11.956] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:2:0) after 1m0s
[12:08:11.940] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:2:0)
[12:08:11.940] Timer tick, asking Buildbucket for the build status
[12:08:12.096] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:08:12.096] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:3:0) after 1m0s
[12:09:12.104] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:3:0)
[12:09:12.104] Timer tick, asking Buildbucket for the build status
[12:09:12.364] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:09:12.364] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:4:0) after 1m0s
[12:10:12.385] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:4:0)
[12:10:12.385] Timer tick, asking Buildbucket for the build status
[12:10:12.434] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:12.434] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:5:0) after 1m0s
[12:11:12.417] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:5:0)
[12:11:12.417] Timer tick, asking Buildbucket for the build status
[12:11:12.593] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:12.593] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:6:0) after 1m0s
[12:12:12.574] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:6:0)
[12:12:12.574] Timer tick, asking Buildbucket for the build status
[12:12:12.695] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:12.695] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:7:0) after 1m0s
[12:13:12.688] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:7:0)
[12:13:12.688] Timer tick, asking Buildbucket for the build status
[12:13:12.793] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:12.793] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:8:0) after 1m0s
[12:14:12.828] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:8:0)
[12:14:12.828] Timer tick, asking Buildbucket for the build status
[12:14:12.880] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:12.880] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:9:0) after 1m0s
[12:15:12.934] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:9:0)
[12:15:12.934] Timer tick, asking Buildbucket for the build status
[12:15:13.020] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:13.020] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:10:0) after 1m0s
[12:16:13.033] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:10:0)
[12:16:13.033] Timer tick, asking Buildbucket for the build status
[12:16:13.088] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:16:13.088] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:11:0) after 1m0s
[12:17:13.121] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:11:0)
[12:17:13.121] Timer tick, asking Buildbucket for the build status
[12:17:14.351] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:14.351] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:12:0) after 1m0s
[12:18:14.374] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:12:0)
[12:18:14.374] Timer tick, asking Buildbucket for the build status
[12:18:14.423] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:14.423] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:13:0) after 1m0s
[12:19:14.488] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:13:0)
[12:19:14.488] Timer tick, asking Buildbucket for the build status
[12:19:14.646] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:14.646] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:14:0) after 1m0s
[12:20:14.663] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:14:0)
[12:20:14.663] Timer tick, asking Buildbucket for the build status
[12:20:14.780] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:14.780] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:15:0) after 1m0s
[12:21:14.801] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:15:0)
[12:21:14.801] Timer tick, asking Buildbucket for the build status
[12:21:14.914] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:21:14.914] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:16:0) after 1m0s
[12:22:14.937] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:16:0)
[12:22:14.938] Timer tick, asking Buildbucket for the build status
[12:22:14.994] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:14.994] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:17:0) after 1m0s
[12:23:15.007] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:17:0)
[12:23:15.007] Timer tick, asking Buildbucket for the build status
[12:23:15.080] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:15.080] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:18:0) after 1m0s
[12:24:15.100] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:18:0)
[12:24:15.100] Timer tick, asking Buildbucket for the build status
[12:24:15.200] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:24:15.200] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:19:0) after 1m0s
[12:25:15.193] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:19:0)
[12:25:15.193] Timer tick, asking Buildbucket for the build status
[12:25:15.270] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:15.270] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:20:0) after 1m0s
[12:26:15.239] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:20:0)
[12:26:15.239] Timer tick, asking Buildbucket for the build status
[12:26:15.304] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:15.304] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:21:0) after 1m0s
[12:27:15.311] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:21:0)
[12:27:15.311] Timer tick, asking Buildbucket for the build status
[12:27:15.375] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:27:15.375] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:22:0) after 1m0s
[12:28:15.396] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:22:0)
[12:28:15.396] Timer tick, asking Buildbucket for the build status
[12:28:15.447] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:28:15.447] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:23:0) after 1m0s
[12:29:15.464] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:23:0)
[12:29:15.464] Timer tick, asking Buildbucket for the build status
[12:29:15.594] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:29:15.594] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:24:0) after 1m0s
[12:30:15.616] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:24:0)
[12:30:15.616] Timer tick, asking Buildbucket for the build status
[12:30:15.666] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:30:15.666] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:25:0) after 1m0s
[12:31:15.636] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:25:0)
[12:31:15.636] Timer tick, asking Buildbucket for the build status
[12:31:15.712] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:15.712] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:26:0) after 1m0s
[12:32:15.740] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:26:0)
[12:32:15.740] Timer tick, asking Buildbucket for the build status
[12:32:15.802] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:32:15.802] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:27:0) after 1m0s
[12:33:15.808] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:27:0)
[12:33:15.808] Timer tick, asking Buildbucket for the build status
[12:33:15.868] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:33:15.868] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:28:0) after 1m0s
[12:34:15.886] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:28:0)
[12:34:15.886] Timer tick, asking Buildbucket for the build status
[12:34:15.955] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:34:15.955] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:29:0) after 1m0s
[12:35:16.001] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:29:0)
[12:35:16.001] Timer tick, asking Buildbucket for the build status
[12:35:16.104] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:35:16.104] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:30:0) after 1m0s
[12:36:16.094] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:30:0)
[12:36:16.094] Timer tick, asking Buildbucket for the build status
[12:36:16.163] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:36:16.163] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:31:0) after 1m0s
[12:37:16.179] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:31:0)
[12:37:16.179] Timer tick, asking Buildbucket for the build status
[12:37:16.262] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:37:16.262] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:32:0) after 1m0s
[12:38:16.261] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:32:0)
[12:38:16.261] Timer tick, asking Buildbucket for the build status
[12:38:16.419] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:16.419] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:33:0) after 1m0s
[12:39:16.451] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:33:0)
[12:39:16.451] Timer tick, asking Buildbucket for the build status
[12:39:16.938] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:16.938] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:34:0) after 1m0s
[12:40:16.931] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:34:0)
[12:40:16.931] Timer tick, asking Buildbucket for the build status
[12:40:16.989] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:16.989] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:35:0) after 1m0s
[12:41:16.990] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:35:0)
[12:41:16.990] Timer tick, asking Buildbucket for the build status
[12:41:17.038] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:17.038] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:36:0) after 1m0s
[12:42:17.064] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:36:0)
[12:42:17.064] Timer tick, asking Buildbucket for the build status
[12:42:17.138] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:17.138] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:37:0) after 1m0s
[12:43:17.143] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:37:0)
[12:43:17.143] Timer tick, asking Buildbucket for the build status
[12:43:17.234] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:17.234] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:38:0) after 1m0s
[12:44:17.209] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:38:0)
[12:44:17.209] Timer tick, asking Buildbucket for the build status
[12:44:17.744] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:17.744] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:39:0) after 1m0s
[12:45:17.817] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:39:0)
[12:45:17.817] Timer tick, asking Buildbucket for the build status
[12:45:17.919] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:17.919] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:40:0) after 1m0s
[12:46:17.941] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:40:0)
[12:46:17.941] Timer tick, asking Buildbucket for the build status
[12:46:18.073] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:18.074] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:41:0) after 1m0s
[12:47:18.057] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:41:0)
[12:47:18.057] Timer tick, asking Buildbucket for the build status
[12:47:18.146] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:18.146] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:42:0) after 1m0s
[12:48:18.150] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:42:0)
[12:48:18.150] Timer tick, asking Buildbucket for the build status
[12:48:18.458] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:18.458] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:43:0) after 1m0s
[12:49:18.478] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:43:0)
[12:49:18.478] Timer tick, asking Buildbucket for the build status
[12:49:18.557] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:18.557] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:44:0) after 1m0s
[12:50:18.588] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:44:0)
[12:50:18.588] Timer tick, asking Buildbucket for the build status
[12:50:18.636] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:18.636] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:45:0) after 1m0s
[12:51:18.652] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:45:0)
[12:51:18.652] Timer tick, asking Buildbucket for the build status
[12:51:18.829] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:18.829] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:46:0) after 1m0s
[12:52:18.849] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:46:0)
[12:52:18.849] Timer tick, asking Buildbucket for the build status
[12:52:18.917] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:18.917] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:47:0) after 1m0s
[12:53:18.923] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:47:0)
[12:53:18.923] Timer tick, asking Buildbucket for the build status
[12:53:18.995] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:18.995] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:48:0) after 1m0s
[12:54:18.990] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:48:0)
[12:54:18.990] Timer tick, asking Buildbucket for the build status
[12:54:19.198] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:19.198] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:49:0) after 1m0s
[12:55:19.217] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:49:0)
[12:55:19.217] Timer tick, asking Buildbucket for the build status
[12:55:19.347] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:19.347] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:50:0) after 1m0s
[12:56:19.323] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:50:0)
[12:56:19.323] Timer tick, asking Buildbucket for the build status
[12:56:19.507] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:19.507] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:51:0) after 1m0s
[12:57:19.502] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:51:0)
[12:57:19.502] Timer tick, asking Buildbucket for the build status
[12:57:19.552] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:57:19.552] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:52:0) after 1m0s
[12:58:19.583] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:52:0)
[12:58:19.583] Timer tick, asking Buildbucket for the build status
[12:58:19.704] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:58:19.704] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:53:0) after 1m0s
[12:59:19.703] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:53:0)
[12:59:19.703] Timer tick, asking Buildbucket for the build status
[12:59:19.752] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:19.752] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:54:0) after 1m0s
[13:00:19.769] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:54:0)
[13:00:19.769] Timer tick, asking Buildbucket for the build status
[13:00:19.912] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:19.912] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:55:0) after 1m0s
[13:01:19.889] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:55:0)
[13:01:19.889] Timer tick, asking Buildbucket for the build status
[13:01:20.081] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:20.081] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:56:0) after 1m0s
[13:02:20.109] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:56:0)
[13:02:20.109] Timer tick, asking Buildbucket for the build status
[13:02:20.179] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:20.179] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:57:0) after 1m0s
[13:03:20.199] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:57:0)
[13:03:20.199] Timer tick, asking Buildbucket for the build status
[13:03:20.256] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:20.256] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:58:0) after 1m0s
[13:04:20.241] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:58:0)
[13:04:20.241] Timer tick, asking Buildbucket for the build status
[13:04:20.326] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:20.326] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:59:0) after 1m0s
[13:05:20.298] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:59:0)
[13:05:20.298] Timer tick, asking Buildbucket for the build status
[13:05:20.397] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:20.397] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:60:0) after 1m0s
[13:06:20.419] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:60:0)
[13:06:20.420] Timer tick, asking Buildbucket for the build status
[13:06:20.499] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:20.499] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:61:0) after 1m0s
[13:07:20.521] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:61:0)
[13:07:20.521] Timer tick, asking Buildbucket for the build status
[13:07:20.610] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:20.610] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:62:0) after 1m0s
[13:08:20.631] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:62:0)
[13:08:20.631] Timer tick, asking Buildbucket for the build status
[13:08:20.697] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:20.697] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:63:0) after 1m0s
[13:09:20.711] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:63:0)
[13:09:20.711] Timer tick, asking Buildbucket for the build status
[13:09:20.841] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:20.841] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:64:0) after 1m0s
[13:10:20.823] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:64:0)
[13:10:20.823] Timer tick, asking Buildbucket for the build status
[13:10:20.976] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:20.976] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:65:0) after 1m0s
[13:11:20.999] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:65:0)
[13:11:20.999] Timer tick, asking Buildbucket for the build status
[13:11:21.140] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:21.140] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:66:0) after 1m0s
[13:12:21.141] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:66:0)
[13:12:21.142] Timer tick, asking Buildbucket for the build status
[13:12:21.291] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:21.291] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:67:0) after 1m0s
[13:13:21.280] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:67:0)
[13:13:21.280] Timer tick, asking Buildbucket for the build status
[13:13:21.334] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:21.334] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:68:0) after 1m0s
[13:14:21.338] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:68:0)
[13:14:21.338] Timer tick, asking Buildbucket for the build status
[13:14:21.413] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:21.413] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:69:0) after 1m0s
[13:15:21.398] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:69:0)
[13:15:21.398] Timer tick, asking Buildbucket for the build status
[13:15:21.475] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:21.475] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:70:0) after 1m0s
[13:16:21.495] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:70:0)
[13:16:21.495] Timer tick, asking Buildbucket for the build status
[13:16:21.620] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:21.620] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:71:0) after 1m0s
[13:17:21.616] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:71:0)
[13:17:21.616] Timer tick, asking Buildbucket for the build status
[13:17:21.669] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:21.669] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:72:0) after 1m0s
[13:18:21.687] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:72:0)
[13:18:21.687] Timer tick, asking Buildbucket for the build status
[13:18:21.793] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:21.793] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:73:0) after 1m0s
[13:19:21.785] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:73:0)
[13:19:21.785] Timer tick, asking Buildbucket for the build status
[13:19:21.891] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:21.891] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:74:0) after 1m0s
[13:20:21.895] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:74:0)
[13:20:21.895] Timer tick, asking Buildbucket for the build status
[13:20:22.121] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:22.121] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:75:0) after 1m0s
[13:21:22.132] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:75:0)
[13:21:22.132] Timer tick, asking Buildbucket for the build status
[13:21:22.185] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:22.185] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:76:0) after 1m0s
[13:22:22.198] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:76:0)
[13:22:22.198] Timer tick, asking Buildbucket for the build status
[13:22:22.296] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:22.296] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:77:0) after 1m0s
[13:23:22.290] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:77:0)
[13:23:22.290] Timer tick, asking Buildbucket for the build status
[13:23:22.406] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:22.406] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:78:0) after 1m0s
[13:24:22.398] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:78:0)
[13:24:22.398] Timer tick, asking Buildbucket for the build status
[13:24:22.465] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:24:22.465] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:79:0) after 1m0s
[13:25:22.481] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:79:0)
[13:25:22.481] Timer tick, asking Buildbucket for the build status
[13:25:22.532] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:22.532] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:80:0) after 1m0s
[13:26:22.554] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:80:0)
[13:26:22.554] Timer tick, asking Buildbucket for the build status
[13:26:22.625] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:22.625] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:81:0) after 1m0s
[13:27:22.633] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:81:0)
[13:27:22.633] Timer tick, asking Buildbucket for the build status
[13:27:22.715] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:27:22.715] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:82:0) after 1m0s
[13:28:22.690] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:82:0)
[13:28:22.690] Timer tick, asking Buildbucket for the build status
[13:28:22.757] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:22.757] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:83:0) after 1m0s
[13:29:22.772] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:83:0)
[13:29:22.772] Timer tick, asking Buildbucket for the build status
[13:29:22.823] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:22.823] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:84:0) after 1m0s
[13:30:22.808] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:84:0)
[13:30:22.808] Timer tick, asking Buildbucket for the build status
[13:30:23.052] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:23.053] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:85:0) after 1m0s
[13:31:23.076] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:85:0)
[13:31:23.076] Timer tick, asking Buildbucket for the build status
[13:31:23.291] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:23.291] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:86:0) after 1m0s
[13:32:23.279] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:86:0)
[13:32:23.279] Timer tick, asking Buildbucket for the build status
[13:32:23.350] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:23.350] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:87:0) after 1m0s
[13:33:23.338] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:87:0)
[13:33:23.338] Timer tick, asking Buildbucket for the build status
[13:33:23.406] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:23.406] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:88:0) after 1m0s
[13:34:23.399] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:88:0)
[13:34:23.399] Timer tick, asking Buildbucket for the build status
[13:34:23.497] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:23.497] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:89:0) after 1m0s
[13:35:23.520] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:89:0)
[13:35:23.520] Timer tick, asking Buildbucket for the build status
[13:35:23.571] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:23.571] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:90:0) after 1m0s
[13:36:23.597] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:90:0)
[13:36:23.597] Timer tick, asking Buildbucket for the build status
[13:36:23.741] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:36:23.741] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:91:0) after 1m0s
[13:37:23.762] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:91:0)
[13:37:23.762] Timer tick, asking Buildbucket for the build status
[13:37:23.835] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:23.835] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:92:0) after 1m0s
[13:38:23.874] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:92:0)
[13:38:23.874] Timer tick, asking Buildbucket for the build status
[13:38:24.001] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:24.001] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:93:0) after 1m0s
[13:39:24.016] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:93:0)
[13:39:24.016] Timer tick, asking Buildbucket for the build status
[13:39:24.078] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:24.078] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:94:0) after 1m0s
[13:40:24.112] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:94:0)
[13:40:24.112] Timer tick, asking Buildbucket for the build status
[13:40:24.179] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:24.179] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:95:0) after 1m0s
[13:41:24.201] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:95:0)
[13:41:24.201] Timer tick, asking Buildbucket for the build status
[13:41:24.264] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:24.264] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:96:0) after 1m0s
[13:42:24.302] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:96:0)
[13:42:24.302] Timer tick, asking Buildbucket for the build status
[13:42:24.380] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:24.380] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:97:0) after 1m0s
[13:43:24.402] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:97:0)
[13:43:24.402] Timer tick, asking Buildbucket for the build status
[13:43:24.453] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:24.453] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:98:0) after 1m0s
[13:44:24.474] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:98:0)
[13:44:24.474] Timer tick, asking Buildbucket for the build status
[13:44:24.682] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:24.682] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:99:0) after 1m0s
[13:45:24.666] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:99:0)
[13:45:24.666] Timer tick, asking Buildbucket for the build status
[13:45:24.747] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:24.747] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:100:0) after 1m0s
[13:46:24.770] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:100:0)
[13:46:24.770] Timer tick, asking Buildbucket for the build status
[13:46:24.958] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:46:24.958] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:101:0) after 1m0s
[13:47:24.987] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:101:0)
[13:47:24.987] Timer tick, asking Buildbucket for the build status
[13:47:25.112] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:47:25.112] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:102:0) after 1m0s
[13:48:25.134] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:102:0)
[13:48:25.134] Timer tick, asking Buildbucket for the build status
[13:48:25.186] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:48:25.186] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:103:0) after 1m0s
[13:49:25.229] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:103:0)
[13:49:25.229] Timer tick, asking Buildbucket for the build status
[13:49:25.373] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:49:25.373] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:104:0) after 1m0s
[13:50:25.362] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:104:0)
[13:50:25.362] Timer tick, asking Buildbucket for the build status
[13:50:25.493] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:50:25.493] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:105:0) after 1m0s
[13:51:25.487] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:105:0)
[13:51:25.487] Timer tick, asking Buildbucket for the build status
[13:51:25.542] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:51:25.542] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:106:0) after 1m0s
[13:52:25.525] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:106:0)
[13:52:25.525] Timer tick, asking Buildbucket for the build status
[13:52:25.591] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:52:25.591] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:107:0) after 1m0s
[13:53:25.595] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:107:0)
[13:53:25.595] Timer tick, asking Buildbucket for the build status
[13:53:25.645] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:53:25.645] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:108:0) after 1m0s
[13:54:25.675] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:108:0)
[13:54:25.675] Timer tick, asking Buildbucket for the build status
[13:54:25.753] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:54:25.753] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:109:0) after 1m0s
[13:55:25.780] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:109:0)
[13:55:25.780] Timer tick, asking Buildbucket for the build status
[13:55:25.858] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:55:25.858] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:110:0) after 1m0s
[13:56:25.838] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:110:0)
[13:56:25.838] Timer tick, asking Buildbucket for the build status
[13:56:26.031] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:56:26.031] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:111:0) after 1m0s
[13:57:26.059] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:111:0)
[13:57:26.059] Timer tick, asking Buildbucket for the build status
[13:57:26.270] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:57:26.270] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:112:0) after 1m0s
[13:58:26.283] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:112:0)
[13:58:26.283] Timer tick, asking Buildbucket for the build status
[13:58:26.406] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:58:26.406] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:113:0) after 1m0s
[13:59:26.394] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:113:0)
[13:59:26.394] Timer tick, asking Buildbucket for the build status
[13:59:26.466] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:59:26.466] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:114:0) after 1m0s
[14:00:26.445] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:114:0)
[14:00:26.445] Timer tick, asking Buildbucket for the build status
[14:00:26.581] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:00:26.581] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:115:0) after 1m0s
[14:01:26.576] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:115:0)
[14:01:26.576] Timer tick, asking Buildbucket for the build status
[14:01:26.771] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:01:26.771] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:116:0) after 1m0s
[14:02:26.796] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:116:0)
[14:02:26.796] Timer tick, asking Buildbucket for the build status
[14:02:26.946] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:02:26.946] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:117:0) after 1m0s
[14:03:26.965] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:117:0)
[14:03:26.965] Timer tick, asking Buildbucket for the build status
[14:03:27.030] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:03:27.030] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:118:0) after 1m0s
[14:04:27.047] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:118:0)
[14:04:27.047] Timer tick, asking Buildbucket for the build status
[14:04:27.168] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:04:27.168] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:119:0) after 1m0s
[14:05:27.189] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:119:0)
[14:05:27.189] Timer tick, asking Buildbucket for the build status
[14:05:27.255] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:05:27.255] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:120:0) after 1m0s
[14:06:27.274] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:120:0)
[14:06:27.274] Timer tick, asking Buildbucket for the build status
[14:06:27.353] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:06:27.353] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:121:0) after 1m0s
[14:07:27.377] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:121:0)
[14:07:27.377] Timer tick, asking Buildbucket for the build status
[14:07:27.435] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:07:27.435] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:122:0) after 1m0s
[14:08:27.439] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:122:0)
[14:08:27.439] Timer tick, asking Buildbucket for the build status
[14:08:27.499] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:08:27.499] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:123:0) after 1m0s
[14:09:27.473] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:123:0)
[14:09:27.473] Timer tick, asking Buildbucket for the build status
[14:09:27.567] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:09:27.567] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:124:0) after 1m0s
[14:10:27.588] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:124:0)
[14:10:27.588] Timer tick, asking Buildbucket for the build status
[14:10:27.761] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:10:27.761] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:125:0) after 1m0s
[14:11:27.788] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:125:0)
[14:11:27.788] Timer tick, asking Buildbucket for the build status
[14:11:27.908] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:11:27.908] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:126:0) after 1m0s
[14:12:27.928] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:126:0)
[14:12:27.928] Timer tick, asking Buildbucket for the build status
[14:12:28.117] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:12:28.117] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:127:0) after 1m0s
[14:13:28.144] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:127:0)
[14:13:28.144] Timer tick, asking Buildbucket for the build status
[14:13:28.247] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:13:28.247] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:128:0) after 1m0s
[14:14:28.269] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:128:0)
[14:14:28.269] Timer tick, asking Buildbucket for the build status
[14:14:28.337] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:14:28.337] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:129:0) after 1m0s
[14:15:28.370] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:129:0)
[14:15:28.370] Timer tick, asking Buildbucket for the build status
[14:15:28.479] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:15:28.479] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:130:0) after 1m0s
[14:16:28.502] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:130:0)
[14:16:28.502] Timer tick, asking Buildbucket for the build status
[14:16:28.569] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:16:28.569] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:131:0) after 1m0s
[14:17:28.558] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:131:0)
[14:17:28.558] Timer tick, asking Buildbucket for the build status
[14:17:28.635] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:17:28.635] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:132:0) after 1m0s
[14:18:28.637] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:132:0)
[14:18:28.637] Timer tick, asking Buildbucket for the build status
[14:18:28.724] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:18:28.724] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:133:0) after 1m0s
[14:19:28.746] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:133:0)
[14:19:28.746] Timer tick, asking Buildbucket for the build status
[14:19:28.872] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:19:28.872] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:134:0) after 1m0s
[14:20:28.907] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:134:0)
[14:20:28.907] Timer tick, asking Buildbucket for the build status
[14:20:28.960] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:20:28.960] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:135:0) after 1m0s
[14:21:28.979] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:135:0)
[14:21:28.979] Timer tick, asking Buildbucket for the build status
[14:21:29.051] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:21:29.051] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:136:0) after 1m0s
[14:22:29.078] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:136:0)
[14:22:29.078] Timer tick, asking Buildbucket for the build status
[14:22:29.126] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:22:29.126] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:137:0) after 1m0s
[14:23:29.196] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:137:0)
[14:23:29.196] Timer tick, asking Buildbucket for the build status
[14:23:29.301] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:23:29.301] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:138:0) after 1m0s
[14:24:29.317] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:138:0)
[14:24:29.317] Timer tick, asking Buildbucket for the build status
[14:24:29.396] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:24:29.396] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:139:0) after 1m0s
[14:25:29.376] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:139:0)
[14:25:29.376] Timer tick, asking Buildbucket for the build status
[14:25:29.450] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:25:29.450] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:140:0) after 1m0s
[14:26:29.423] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:140:0)
[14:26:29.423] Timer tick, asking Buildbucket for the build status
[14:26:29.506] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:26:29.506] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:141:0) after 1m0s
[14:27:29.504] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:141:0)
[14:27:29.504] Timer tick, asking Buildbucket for the build status
[14:27:29.785] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:27:29.785] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:142:0) after 1m0s
[14:28:29.775] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:142:0)
[14:28:29.775] Timer tick, asking Buildbucket for the build status
[14:28:29.875] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:28:29.875] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:143:0) after 1m0s
[14:29:29.899] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:143:0)
[14:29:29.899] Timer tick, asking Buildbucket for the build status
[14:29:30.017] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:29:30.017] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:144:0) after 1m0s
[14:30:30.172] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:144:0)
[14:30:30.172] Timer tick, asking Buildbucket for the build status
[14:30:30.222] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:30:30.222] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:145:0) after 1m0s
[14:31:30.339] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:145:0)
[14:31:30.339] Timer tick, asking Buildbucket for the build status
[14:31:30.443] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:31:30.443] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:146:0) after 1m0s
[14:32:30.423] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:146:0)
[14:32:30.423] Timer tick, asking Buildbucket for the build status
[14:32:30.678] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:32:30.678] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:147:0) after 1m0s
[14:33:30.672] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:147:0)
[14:33:30.672] Timer tick, asking Buildbucket for the build status
[14:33:30.899] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:33:30.899] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:148:0) after 1m0s
[14:34:30.890] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:148:0)
[14:34:30.890] Timer tick, asking Buildbucket for the build status
[14:34:31.077] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:34:31.077] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:149:0) after 1m0s
[14:35:31.100] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:149:0)
[14:35:31.100] Timer tick, asking Buildbucket for the build status
[14:35:31.383] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:35:31.383] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:150:0) after 1m0s
[14:36:31.359] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:150:0)
[14:36:31.359] Timer tick, asking Buildbucket for the build status
[14:36:31.492] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:36:31.492] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:151:0) after 1m0s
[14:37:31.463] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:151:0)
[14:37:31.463] Timer tick, asking Buildbucket for the build status
[14:37:31.553] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:37:31.553] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:152:0) after 1m0s
[14:38:31.646] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:152:0)
[14:38:31.646] Timer tick, asking Buildbucket for the build status
[14:38:31.859] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:38:31.859] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:153:0) after 1m0s
[14:39:31.877] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:153:0)
[14:39:31.877] Timer tick, asking Buildbucket for the build status
[14:39:31.955] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:39:31.955] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:154:0) after 1m0s
[14:40:31.973] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:154:0)
[14:40:31.973] Timer tick, asking Buildbucket for the build status
[14:40:32.069] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:40:32.069] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:155:0) after 1m0s
[14:41:32.076] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:155:0)
[14:41:32.076] Timer tick, asking Buildbucket for the build status
[14:41:32.154] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:41:32.154] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:156:0) after 1m0s
[14:42:32.362] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:156:0)
[14:42:32.362] Timer tick, asking Buildbucket for the build status
[14:42:32.464] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:42:32.464] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:157:0) after 1m0s
[14:43:32.498] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:157:0)
[14:43:32.498] Timer tick, asking Buildbucket for the build status
[14:43:32.755] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:43:32.755] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:158:0) after 1m0s
[14:44:32.735] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:158:0)
[14:44:32.735] Timer tick, asking Buildbucket for the build status
[14:44:32.990] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:44:32.990] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:159:0) after 1m0s
[14:45:33.053] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:159:0)
[14:45:33.053] Timer tick, asking Buildbucket for the build status
[14:45:33.204] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:45:33.204] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:160:0) after 1m0s
[14:46:33.212] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:160:0)
[14:46:33.212] Timer tick, asking Buildbucket for the build status
[14:46:33.262] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:46:33.262] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:161:0) after 1m0s
[14:47:33.313] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:161:0)
[14:47:33.313] Timer tick, asking Buildbucket for the build status
[14:47:33.378] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:47:33.378] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:162:0) after 1m0s
[14:48:33.387] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:162:0)
[14:48:33.387] Timer tick, asking Buildbucket for the build status
[14:48:33.503] Build 8919826450529704720: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[14:48:33.503] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:163:0) after 1m0s
[14:48:54.659] Received PubSub notification, asking Buildbucket for the build status
[14:48:54.720] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:33.571] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:163:0)
[14:49:33.571] Timer tick, asking Buildbucket for the build status
[14:49:33.805] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:33.805] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:165:0) after 1m0s
[14:50:33.830] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:165:0)
[14:50:33.830] Timer tick, asking Buildbucket for the build status
[14:50:33.933] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:33.933] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:166:0) after 1m0s
[14:51:33.954] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:166:0)
[14:51:33.954] Timer tick, asking Buildbucket for the build status
[14:51:33.990] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:33.990] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:167:0) after 1m0s
[14:52:33.972] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:167:0)
[14:52:33.972] Timer tick, asking Buildbucket for the build status
[14:52:34.014] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:34.014] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:168:0) after 1m0s
[14:53:34.048] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:168:0)
[14:53:34.048] Timer tick, asking Buildbucket for the build status
[14:53:34.280] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:34.280] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:169:0) after 1m0s
[14:54:34.267] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:169:0)
[14:54:34.267] Timer tick, asking Buildbucket for the build status
[14:54:34.316] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:34.316] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:170:0) after 1m0s
[14:55:34.376] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:170:0)
[14:55:34.376] Timer tick, asking Buildbucket for the build status
[14:55:34.436] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:34.436] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:171:0) after 1m0s
[14:56:34.437] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:171:0)
[14:56:34.437] Timer tick, asking Buildbucket for the build status
[14:56:34.571] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:34.571] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:172:0) after 1m0s
[14:57:34.593] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:172:0)
[14:57:34.593] Timer tick, asking Buildbucket for the build status
[14:57:34.654] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:34.654] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:173:0) after 1m0s
[14:58:34.689] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:173:0)
[14:58:34.689] Timer tick, asking Buildbucket for the build status
[14:58:34.771] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:34.771] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:174:0) after 1m0s
[14:59:34.791] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:174:0)
[14:59:34.791] Timer tick, asking Buildbucket for the build status
[14:59:34.874] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:34.874] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:175:0) after 1m0s
[15:00:34.893] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:175:0)
[15:00:34.893] Timer tick, asking Buildbucket for the build status
[15:00:34.992] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:34.992] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:176:0) after 1m0s
[15:01:34.979] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:176:0)
[15:01:34.979] Timer tick, asking Buildbucket for the build status
[15:01:35.141] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:35.141] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:177:0) after 1m0s
[15:02:35.149] Handling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:177:0)
[15:02:35.149] Timer tick, asking Buildbucket for the build status
[15:02:35.299] Build 8919826450529704720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:35.299] Scheduling timer "check-buildbucket-build-status" (r8/archive:9085256512694169136:178:0) after 1m0s
[15:02:43.445] Received PubSub notification, asking Buildbucket for the build status
[15:02:43.483] Build 8919826450529704720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:02:43.483] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"completed_ts": "1551798145902612",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551787629536216",
"id": "8919826450529704720",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"7882a8141f85d4330d84b34a1448a5d531426066\"}}",
"project": "r8",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"inside_docker\": [\"0\"], \"locale\": [\"en_US.UTF-8\"], \"image\": [\"chrome-xenial-18041000-0b3ff326a44\"], \"kvm\": [\"0\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4143-92e279d\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"id\": [\"gce-xenial-396d8f4b-us-east1-b-hp3z\"], \"pool\": [\"luci.r8.ci\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildnumber\": 49, \"repository\": \"https://r8.googlesource.com/r8\", \"buildername\": \"archive\", \"recipe\": \"rex\", \"mastername\": \"internal.client.r8\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"7882a8141f85d4330d84b34a1448a5d531426066\", \"archive\": \"True\", \"bot_id\": \"gce-xenial-396d8f4b-us-east1-b-hp3z\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551797333583617",
"status": "COMPLETED",
"status_changed_ts": "1551798161197540",
"tags": [
"build_address:luci.r8.ci/archive/49",
"builder:archive",
"buildset:commit/git/7882a8141f85d4330d84b34a1448a5d531426066",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/7882a8141f85d4330d84b34a1448a5d531426066",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9085256512694169136",
"scheduler_job_id:r8/archive",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8919826450529704720/+/annotations",
"swarming_tag:luci_project:r8",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:rex",
"swarming_tag:recipe_package:infra_internal/recipe_bundles/chrome-internal.googlesource.com/chrome/tools/build_limited/scripts/slave",
"swarming_task_id:436691aa312cde10",
"user_agent:luci-scheduler"
],
"updated_ts": "1551798161199114",
"url": "https://ci.chromium.org/b/8919826450529704720",
"utcnow_ts": "1551798163475629"
}
}
[15:02:43.483] Invocation finished in 2h55m35.39434084s with status SUCCEEDED