[12:08:42.301] New invocation is queued and will start shortly
[12:08:43.385] Starting the invocation (attempt 1)
[12:08:43.418] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:08:43.418] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:08:43.418] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9084984623023382400",
"parameters_json": "{\"builder_name\":\"archive\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"082351d92119f30d4ae074ef3990ec2880b85664\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:archive",
"scheduler_invocation_id:9084984623023382400",
"scheduler_job_id:r8/archive",
"user_agent:luci-scheduler",
"buildset:commit/git/082351d92119f30d4ae074ef3990ec2880b85664",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/082351d92119f30d4ae074ef3990ec2880b85664",
"gitiles_ref:refs/heads/master"
]
}
[12:08:44.651] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552046923488897",
"id": "8919554561115107392",
"lease_expiration_ts": "1552261124217519",
"lease_key": "1308839662",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"082351d92119f30d4ae074ef3990ec2880b85664\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1552046924315207",
"tags": [
"build_address:luci.r8.ci/archive/84",
"builder:archive",
"buildset:commit/git/082351d92119f30d4ae074ef3990ec2880b85664",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/082351d92119f30d4ae074ef3990ec2880b85664",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084984623023382400",
"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/8919554561115107392/+/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:43760628dac21f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552046924315324",
"url": "https://ci.chromium.org/b/8919554561115107392",
"utcnow_ts": "1552046924643177"
}
}
[12:08:44.651] Task URL: https://ci.chromium.org/b/8919554561115107392
[12:08:44.651] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:2:0) after 1m0s
[12:09:44.669] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:2:0)
[12:09:44.669] Timer tick, asking Buildbucket for the build status
[12:09:44.756] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:09:44.756] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:3:0) after 1m0s
[12:10:44.779] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:3:0)
[12:10:44.779] Timer tick, asking Buildbucket for the build status
[12:10:44.866] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:44.866] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:4:0) after 1m0s
[12:11:44.841] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:4:0)
[12:11:44.841] Timer tick, asking Buildbucket for the build status
[12:11:45.266] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:45.266] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:5:0) after 1m0s
[12:12:45.283] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:5:0)
[12:12:45.283] Timer tick, asking Buildbucket for the build status
[12:12:45.341] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:45.341] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:6:0) after 1m0s
[12:13:45.382] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:6:0)
[12:13:45.382] Timer tick, asking Buildbucket for the build status
[12:13:45.459] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:45.459] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:7:0) after 1m0s
[12:14:45.460] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:7:0)
[12:14:45.460] Timer tick, asking Buildbucket for the build status
[12:14:45.524] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:45.524] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:8:0) after 1m0s
[12:15:45.547] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:8:0)
[12:15:45.547] Timer tick, asking Buildbucket for the build status
[12:15:45.678] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:45.678] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:9:0) after 1m0s
[12:16:45.696] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:9:0)
[12:16:45.696] Timer tick, asking Buildbucket for the build status
[12:16:45.876] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:16:45.876] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:10:0) after 1m0s
[12:17:45.875] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:10:0)
[12:17:45.875] Timer tick, asking Buildbucket for the build status
[12:17:45.921] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:45.921] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:11:0) after 1m0s
[12:18:45.941] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:11:0)
[12:18:45.941] Timer tick, asking Buildbucket for the build status
[12:18:46.007] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:46.007] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:12:0) after 1m0s
[12:19:45.996] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:12:0)
[12:19:45.996] Timer tick, asking Buildbucket for the build status
[12:19:46.093] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:46.093] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:13:0) after 1m0s
[12:20:46.094] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:13:0)
[12:20:46.094] Timer tick, asking Buildbucket for the build status
[12:20:46.213] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:46.213] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:14:0) after 1m0s
[12:21:46.234] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:14:0)
[12:21:46.234] Timer tick, asking Buildbucket for the build status
[12:21:46.307] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:21:46.307] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:15:0) after 1m0s
[12:22:46.328] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:15:0)
[12:22:46.328] Timer tick, asking Buildbucket for the build status
[12:22:46.387] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:46.387] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:16:0) after 1m0s
[12:23:46.399] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:16:0)
[12:23:46.399] Timer tick, asking Buildbucket for the build status
[12:23:46.470] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:46.470] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:17:0) after 1m0s
[12:24:46.440] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:17:0)
[12:24:46.440] Timer tick, asking Buildbucket for the build status
[12:24:46.549] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:24:46.549] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:18:0) after 1m0s
[12:25:46.575] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:18:0)
[12:25:46.575] Timer tick, asking Buildbucket for the build status
[12:25:46.643] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:46.643] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:19:0) after 1m0s
[12:26:46.671] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:19:0)
[12:26:46.671] Timer tick, asking Buildbucket for the build status
[12:26:46.787] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:46.787] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:20:0) after 1m0s
[12:27:46.806] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:20:0)
[12:27:46.806] Timer tick, asking Buildbucket for the build status
[12:27:47.613] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:27:47.613] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:21:0) after 1m0s
[12:28:47.590] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:21:0)
[12:28:47.590] Timer tick, asking Buildbucket for the build status
[12:28:47.701] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:28:47.701] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:22:0) after 1m0s
[12:29:47.719] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:22:0)
[12:29:47.719] Timer tick, asking Buildbucket for the build status
[12:29:47.788] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:29:47.788] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:23:0) after 1m0s
[12:30:47.812] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:23:0)
[12:30:47.812] Timer tick, asking Buildbucket for the build status
[12:30:47.874] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:30:47.874] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:24:0) after 1m0s
[12:31:47.878] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:24:0)
[12:31:47.878] Timer tick, asking Buildbucket for the build status
[12:31:47.986] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:47.986] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:25:0) after 1m0s
[12:32:48.005] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:25:0)
[12:32:48.005] Timer tick, asking Buildbucket for the build status
[12:32:48.066] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:32:48.066] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:26:0) after 1m0s
[12:33:48.090] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:26:0)
[12:33:48.090] Timer tick, asking Buildbucket for the build status
[12:33:48.197] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:33:48.197] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:27:0) after 1m0s
[12:34:48.216] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:27:0)
[12:34:48.216] Timer tick, asking Buildbucket for the build status
[12:34:48.276] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:34:48.276] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:28:0) after 1m0s
[12:35:48.281] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:28:0)
[12:35:48.281] Timer tick, asking Buildbucket for the build status
[12:35:48.396] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:35:48.396] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:29:0) after 1m0s
[12:36:48.375] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:29:0)
[12:36:48.375] Timer tick, asking Buildbucket for the build status
[12:36:48.443] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:36:48.443] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:30:0) after 1m0s
[12:37:48.474] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:30:0)
[12:37:48.474] Timer tick, asking Buildbucket for the build status
[12:37:48.525] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:37:48.525] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:31:0) after 1m0s
[12:38:48.560] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:31:0)
[12:38:48.561] Timer tick, asking Buildbucket for the build status
[12:38:48.680] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:48.680] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:32:0) after 1m0s
[12:39:48.679] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:32:0)
[12:39:48.679] Timer tick, asking Buildbucket for the build status
[12:39:48.813] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:48.813] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:33:0) after 1m0s
[12:40:48.832] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:33:0)
[12:40:48.832] Timer tick, asking Buildbucket for the build status
[12:40:48.908] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:48.908] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:34:0) after 1m0s
[12:41:48.890] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:34:0)
[12:41:48.890] Timer tick, asking Buildbucket for the build status
[12:41:48.950] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:48.950] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:35:0) after 1m0s
[12:42:48.963] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:35:0)
[12:42:48.963] Timer tick, asking Buildbucket for the build status
[12:42:49.088] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:49.088] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:36:0) after 1m0s
[12:43:49.106] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:36:0)
[12:43:49.106] Timer tick, asking Buildbucket for the build status
[12:43:49.176] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:49.176] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:37:0) after 1m0s
[12:44:49.188] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:37:0)
[12:44:49.188] Timer tick, asking Buildbucket for the build status
[12:44:49.321] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:49.321] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:38:0) after 1m0s
[12:45:49.319] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:38:0)
[12:45:49.319] Timer tick, asking Buildbucket for the build status
[12:45:49.427] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:49.427] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:39:0) after 1m0s
[12:46:49.453] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:39:0)
[12:46:49.453] Timer tick, asking Buildbucket for the build status
[12:46:49.588] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:49.588] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:40:0) after 1m0s
[12:47:49.609] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:40:0)
[12:47:49.609] Timer tick, asking Buildbucket for the build status
[12:47:49.673] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:49.673] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:41:0) after 1m0s
[12:48:49.664] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:41:0)
[12:48:49.664] Timer tick, asking Buildbucket for the build status
[12:48:49.739] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:49.739] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:42:0) after 1m0s
[12:49:49.740] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:42:0)
[12:49:49.740] Timer tick, asking Buildbucket for the build status
[12:49:49.791] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:49.791] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:43:0) after 1m0s
[12:50:49.779] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:43:0)
[12:50:49.779] Timer tick, asking Buildbucket for the build status
[12:50:49.962] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:49.962] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:44:0) after 1m0s
[12:51:49.970] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:44:0)
[12:51:49.970] Timer tick, asking Buildbucket for the build status
[12:51:50.077] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:50.077] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:45:0) after 1m0s
[12:52:50.093] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:45:0)
[12:52:50.093] Timer tick, asking Buildbucket for the build status
[12:52:50.147] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:50.147] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:46:0) after 1m0s
[12:53:50.146] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:46:0)
[12:53:50.146] Timer tick, asking Buildbucket for the build status
[12:53:50.236] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:50.236] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:47:0) after 1m0s
[12:54:50.265] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:47:0)
[12:54:50.265] Timer tick, asking Buildbucket for the build status
[12:54:50.365] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:50.365] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:48:0) after 1m0s
[12:55:50.396] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:48:0)
[12:55:50.396] Timer tick, asking Buildbucket for the build status
[12:55:50.449] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:50.449] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:49:0) after 1m0s
[12:56:50.466] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:49:0)
[12:56:50.466] Timer tick, asking Buildbucket for the build status
[12:56:50.533] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:50.533] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:50:0) after 1m0s
[12:57:50.541] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:50:0)
[12:57:50.541] Timer tick, asking Buildbucket for the build status
[12:57:50.617] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:57:50.617] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:51:0) after 1m0s
[12:58:50.640] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:51:0)
[12:58:50.640] Timer tick, asking Buildbucket for the build status
[12:58:50.697] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:58:50.697] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:52:0) after 1m0s
[12:59:50.703] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:52:0)
[12:59:50.703] Timer tick, asking Buildbucket for the build status
[12:59:50.865] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:59:50.865] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:53:0) after 1m0s
[13:00:50.887] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:53:0)
[13:00:50.887] Timer tick, asking Buildbucket for the build status
[13:00:50.960] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:00:50.960] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:54:0) after 1m0s
[13:01:50.979] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:54:0)
[13:01:50.979] Timer tick, asking Buildbucket for the build status
[13:01:51.128] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:01:51.128] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:55:0) after 1m0s
[13:02:51.133] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:55:0)
[13:02:51.133] Timer tick, asking Buildbucket for the build status
[13:02:51.209] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:02:51.209] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:56:0) after 1m0s
[13:03:51.261] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:56:0)
[13:03:51.261] Timer tick, asking Buildbucket for the build status
[13:03:51.436] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:03:51.436] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:57:0) after 1m0s
[13:04:51.556] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:57:0)
[13:04:51.556] Timer tick, asking Buildbucket for the build status
[13:04:51.620] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:04:51.620] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:58:0) after 1m0s
[13:05:51.647] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:58:0)
[13:05:51.647] Timer tick, asking Buildbucket for the build status
[13:05:51.723] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:05:51.723] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:59:0) after 1m0s
[13:06:51.717] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:59:0)
[13:06:51.717] Timer tick, asking Buildbucket for the build status
[13:06:51.771] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:06:51.771] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:60:0) after 1m0s
[13:07:51.768] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:60:0)
[13:07:51.768] Timer tick, asking Buildbucket for the build status
[13:07:51.891] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:07:51.891] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:61:0) after 1m0s
[13:08:51.944] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:61:0)
[13:08:51.944] Timer tick, asking Buildbucket for the build status
[13:08:52.203] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:08:52.203] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:62:0) after 1m0s
[13:09:52.229] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:62:0)
[13:09:52.229] Timer tick, asking Buildbucket for the build status
[13:09:52.278] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:09:52.278] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:63:0) after 1m0s
[13:10:52.290] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:63:0)
[13:10:52.290] Timer tick, asking Buildbucket for the build status
[13:10:52.367] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:10:52.367] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:64:0) after 1m0s
[13:11:52.389] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:64:0)
[13:11:52.389] Timer tick, asking Buildbucket for the build status
[13:11:52.495] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:11:52.495] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:65:0) after 1m0s
[13:12:52.500] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:65:0)
[13:12:52.500] Timer tick, asking Buildbucket for the build status
[13:12:52.607] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:12:52.607] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:66:0) after 1m0s
[13:13:52.598] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:66:0)
[13:13:52.598] Timer tick, asking Buildbucket for the build status
[13:13:52.658] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:13:52.658] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:67:0) after 1m0s
[13:14:52.687] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:67:0)
[13:14:52.687] Timer tick, asking Buildbucket for the build status
[13:14:52.799] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:14:52.799] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:68:0) after 1m0s
[13:15:52.807] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:68:0)
[13:15:52.807] Timer tick, asking Buildbucket for the build status
[13:15:52.879] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:15:52.879] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:69:0) after 1m0s
[13:16:52.899] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:69:0)
[13:16:52.899] Timer tick, asking Buildbucket for the build status
[13:16:53.086] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:16:53.086] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:70:0) after 1m0s
[13:17:53.414] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:70:0)
[13:17:53.414] Timer tick, asking Buildbucket for the build status
[13:17:53.461] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:17:53.461] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:71:0) after 1m0s
[13:18:53.492] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:71:0)
[13:18:53.492] Timer tick, asking Buildbucket for the build status
[13:18:53.580] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:18:53.580] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:72:0) after 1m0s
[13:19:53.611] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:72:0)
[13:19:53.611] Timer tick, asking Buildbucket for the build status
[13:19:53.863] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:19:53.863] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:73:0) after 1m0s
[13:20:53.838] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:73:0)
[13:20:53.838] Timer tick, asking Buildbucket for the build status
[13:20:53.891] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:20:53.891] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:74:0) after 1m0s
[13:21:53.921] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:74:0)
[13:21:53.921] Timer tick, asking Buildbucket for the build status
[13:21:53.990] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:21:53.990] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:75:0) after 1m0s
[13:22:53.987] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:75:0)
[13:22:53.987] Timer tick, asking Buildbucket for the build status
[13:22:54.142] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:22:54.142] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:76:0) after 1m0s
[13:23:54.163] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:76:0)
[13:23:54.163] Timer tick, asking Buildbucket for the build status
[13:23:54.300] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:23:54.300] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:77:0) after 1m0s
[13:24:54.325] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:77:0)
[13:24:54.325] Timer tick, asking Buildbucket for the build status
[13:24:54.400] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:24:54.400] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:78:0) after 1m0s
[13:25:54.429] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:78:0)
[13:25:54.430] Timer tick, asking Buildbucket for the build status
[13:25:54.493] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:25:54.493] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:79:0) after 1m0s
[13:26:54.489] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:79:0)
[13:26:54.489] Timer tick, asking Buildbucket for the build status
[13:26:54.687] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:26:54.687] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:80:0) after 1m0s
[13:27:54.724] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:80:0)
[13:27:54.724] Timer tick, asking Buildbucket for the build status
[13:27:54.783] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:27:54.783] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:81:0) after 1m0s
[13:28:54.788] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:81:0)
[13:28:54.788] Timer tick, asking Buildbucket for the build status
[13:28:54.849] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:28:54.849] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:82:0) after 1m0s
[13:29:54.849] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:82:0)
[13:29:54.849] Timer tick, asking Buildbucket for the build status
[13:29:55.389] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:29:55.389] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:83:0) after 1m0s
[13:30:55.399] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:83:0)
[13:30:55.399] Timer tick, asking Buildbucket for the build status
[13:30:55.772] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:30:55.772] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:84:0) after 1m0s
[13:31:55.782] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:84:0)
[13:31:55.782] Timer tick, asking Buildbucket for the build status
[13:31:55.835] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:31:55.835] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:85:0) after 1m0s
[13:32:55.858] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:85:0)
[13:32:55.858] Timer tick, asking Buildbucket for the build status
[13:32:55.929] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:32:55.929] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:86:0) after 1m0s
[13:33:55.952] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:86:0)
[13:33:55.952] Timer tick, asking Buildbucket for the build status
[13:33:56.002] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:33:56.002] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:87:0) after 1m0s
[13:34:55.989] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:87:0)
[13:34:55.989] Timer tick, asking Buildbucket for the build status
[13:34:56.081] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:34:56.081] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:88:0) after 1m0s
[13:35:56.124] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:88:0)
[13:35:56.124] Timer tick, asking Buildbucket for the build status
[13:35:56.171] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:35:56.171] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:89:0) after 1m0s
[13:36:56.190] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:89:0)
[13:36:56.190] Timer tick, asking Buildbucket for the build status
[13:36:56.406] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:36:56.406] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:90:0) after 1m0s
[13:37:56.377] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:90:0)
[13:37:56.377] Timer tick, asking Buildbucket for the build status
[13:37:56.451] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:37:56.451] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:91:0) after 1m0s
[13:38:56.492] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:91:0)
[13:38:56.492] Timer tick, asking Buildbucket for the build status
[13:38:56.658] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:38:56.658] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:92:0) after 1m0s
[13:39:56.677] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:92:0)
[13:39:56.677] Timer tick, asking Buildbucket for the build status
[13:39:56.731] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:39:56.731] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:93:0) after 1m0s
[13:40:56.741] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:93:0)
[13:40:56.741] Timer tick, asking Buildbucket for the build status
[13:40:56.805] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:40:56.805] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:94:0) after 1m0s
[13:41:56.784] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:94:0)
[13:41:56.784] Timer tick, asking Buildbucket for the build status
[13:41:56.869] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:41:56.870] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:95:0) after 1m0s
[13:42:56.849] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:95:0)
[13:42:56.849] Timer tick, asking Buildbucket for the build status
[13:42:56.991] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:42:56.991] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:96:0) after 1m0s
[13:43:56.979] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:96:0)
[13:43:56.979] Timer tick, asking Buildbucket for the build status
[13:43:57.096] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:43:57.096] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:97:0) after 1m0s
[13:44:57.108] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:97:0)
[13:44:57.108] Timer tick, asking Buildbucket for the build status
[13:44:57.212] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:44:57.212] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:98:0) after 1m0s
[13:45:57.234] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:98:0)
[13:45:57.234] Timer tick, asking Buildbucket for the build status
[13:45:57.356] Build 8919554561115107392: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[13:45:57.356] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:99:0) after 1m0s
[13:46:53.082] Received PubSub notification, asking Buildbucket for the build status
[13:46:53.205] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:57.377] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:99:0)
[13:46:57.377] Timer tick, asking Buildbucket for the build status
[13:46:57.421] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:57.421] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:101:0) after 1m0s
[13:47:57.448] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:101:0)
[13:47:57.448] Timer tick, asking Buildbucket for the build status
[13:47:57.521] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:57.521] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:102:0) after 1m0s
[13:48:57.543] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:102:0)
[13:48:57.543] Timer tick, asking Buildbucket for the build status
[13:48:57.632] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:57.632] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:103:0) after 1m0s
[13:49:57.668] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:103:0)
[13:49:57.668] Timer tick, asking Buildbucket for the build status
[13:49:57.713] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:57.713] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:104:0) after 1m0s
[13:50:57.768] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:104:0)
[13:50:57.768] Timer tick, asking Buildbucket for the build status
[13:50:57.805] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:57.805] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:105:0) after 1m0s
[13:51:57.828] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:105:0)
[13:51:57.828] Timer tick, asking Buildbucket for the build status
[13:51:57.897] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:57.897] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:106:0) after 1m0s
[13:52:57.901] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:106:0)
[13:52:57.901] Timer tick, asking Buildbucket for the build status
[13:52:57.961] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:57.961] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:107:0) after 1m0s
[13:53:57.985] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:107:0)
[13:53:57.985] Timer tick, asking Buildbucket for the build status
[13:53:58.023] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:58.023] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:108:0) after 1m0s
[13:54:58.096] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:108:0)
[13:54:58.096] Timer tick, asking Buildbucket for the build status
[13:54:58.190] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:58.190] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:109:0) after 1m0s
[13:55:58.211] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:109:0)
[13:55:58.211] Timer tick, asking Buildbucket for the build status
[13:55:58.317] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:58.317] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:110:0) after 1m0s
[13:56:58.355] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:110:0)
[13:56:58.355] Timer tick, asking Buildbucket for the build status
[13:56:58.397] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:58.397] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:111:0) after 1m0s
[13:57:58.453] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:111:0)
[13:57:58.453] Timer tick, asking Buildbucket for the build status
[13:57:58.580] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:58.580] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:112:0) after 1m0s
[13:58:58.591] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:112:0)
[13:58:58.591] Timer tick, asking Buildbucket for the build status
[13:58:58.644] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:58.644] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:113:0) after 1m0s
[13:59:58.641] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:113:0)
[13:59:58.641] Timer tick, asking Buildbucket for the build status
[13:59:58.679] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:58.680] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:114:0) after 1m0s
[14:00:58.694] Handling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:114:0)
[14:00:58.694] Timer tick, asking Buildbucket for the build status
[14:00:58.752] Build 8919554561115107392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:58.752] Scheduling timer "check-buildbucket-build-status" (r8/archive:9084984623023382400:115:0) after 1m0s
[14:01:15.662] Received PubSub notification, asking Buildbucket for the build status
[14:01:15.695] Build 8919554561115107392: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[14:01:15.695] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"completed_ts": "1552053674471967",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1552046923488897",
"id": "8919554561115107392",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"082351d92119f30d4ae074ef3990ec2880b85664\"}}",
"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\": [\"4158-1053c58\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"id\": [\"gce-xenial-396d8f4b-us-east1-b-q1wz\"], \"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\": 84, \"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\": \"082351d92119f30d4ae074ef3990ec2880b85664\", \"archive\": \"True\", \"bot_id\": \"gce-xenial-396d8f4b-us-east1-b-q1wz\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1552052812448619",
"status": "COMPLETED",
"status_changed_ts": "1552053675169319",
"tags": [
"build_address:luci.r8.ci/archive/84",
"builder:archive",
"buildset:commit/git/082351d92119f30d4ae074ef3990ec2880b85664",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/082351d92119f30d4ae074ef3990ec2880b85664",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9084984623023382400",
"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/8919554561115107392/+/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:43760628dac21f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1552053675173599",
"url": "https://ci.chromium.org/b/8919554561115107392",
"utcnow_ts": "1552053675688019"
}
}
[14:01:15.695] Invocation finished in 1h52m33.411546335s with status SUCCEEDED