[08:10:32.673] New invocation is queued and will start shortly
[08:10:34.173] Starting the invocation (attempt 1)
[08:10:34.213] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:10:34.213] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:10:34.213] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9086449158249503280",
"parameters_json": "{\"builder_name\":\"archive\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8.git\",\"revision\":\"b2d27c4647f5f7b5c47087532e94940d21143f70\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:archive",
"scheduler_invocation_id:9086449158249503280",
"scheduler_job_id:r8/archive",
"user_agent:luci-scheduler",
"buildset:commit/git/b2d27c4647f5f7b5c47087532e94940d21143f70",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/b2d27c4647f5f7b5c47087532e94940d21143f70",
"gitiles_ref:refs/heads/master"
]
}
[08:10:35.517] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550650234289425",
"id": "8921019095887972864",
"lease_expiration_ts": "1550769035199346",
"lease_key": "457555198",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8.git\", \"revision\": \"b2d27c4647f5f7b5c47087532e94940d21143f70\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1550650235292318",
"tags": [
"build_address:luci.r8.ci/archive/10",
"builder:archive",
"buildset:commit/git/b2d27c4647f5f7b5c47087532e94940d21143f70",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/b2d27c4647f5f7b5c47087532e94940d21143f70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086449158249503280",
"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/8921019095887972864/+/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:4322c65f4843cd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1550650235292405",
"url": "https://ci.chromium.org/b/8921019095887972864",
"utcnow_ts": "1550650235507772"
}
}
[08:10:35.517] Task URL: https://ci.chromium.org/b/8921019095887972864
[08:10:35.517] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:2:0) after 1m0s
[08:11:30.491] Received PubSub notification, asking Buildbucket for the build status
[08:11:30.601] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:35.534] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:2:0)
[08:11:35.534] Timer tick, asking Buildbucket for the build status
[08:11:35.577] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:35.577] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:4:0) after 1m0s
[08:12:35.597] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:4:0)
[08:12:35.597] Timer tick, asking Buildbucket for the build status
[08:12:35.776] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:35.776] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:5:0) after 1m0s
[08:13:35.801] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:5:0)
[08:13:35.801] Timer tick, asking Buildbucket for the build status
[08:13:35.915] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:35.915] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:6:0) after 1m0s
[08:14:35.941] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:6:0)
[08:14:35.941] Timer tick, asking Buildbucket for the build status
[08:14:36.003] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:36.003] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:7:0) after 1m0s
[08:15:36.074] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:7:0)
[08:15:36.074] Timer tick, asking Buildbucket for the build status
[08:15:36.155] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:36.155] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:8:0) after 1m0s
[08:16:36.183] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:8:0)
[08:16:36.183] Timer tick, asking Buildbucket for the build status
[08:16:36.251] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:36.251] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:9:0) after 1m0s
[08:17:36.306] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:9:0)
[08:17:36.306] Timer tick, asking Buildbucket for the build status
[08:17:36.353] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:36.353] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:10:0) after 1m0s
[08:18:36.373] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:10:0)
[08:18:36.373] Timer tick, asking Buildbucket for the build status
[08:18:36.496] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:36.496] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:11:0) after 1m0s
[08:19:36.519] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:11:0)
[08:19:36.519] Timer tick, asking Buildbucket for the build status
[08:19:36.599] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:36.599] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:12:0) after 1m0s
[08:20:36.619] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:12:0)
[08:20:36.619] Timer tick, asking Buildbucket for the build status
[08:20:36.696] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:36.696] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:13:0) after 1m0s
[08:21:36.711] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:13:0)
[08:21:36.711] Timer tick, asking Buildbucket for the build status
[08:21:36.772] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:36.772] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:14:0) after 1m0s
[08:22:36.798] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:14:0)
[08:22:36.798] Timer tick, asking Buildbucket for the build status
[08:22:36.843] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:36.843] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:15:0) after 1m0s
[08:23:36.860] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:15:0)
[08:23:36.860] Timer tick, asking Buildbucket for the build status
[08:23:36.921] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:36.921] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:16:0) after 1m0s
[08:24:36.942] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:16:0)
[08:24:36.942] Timer tick, asking Buildbucket for the build status
[08:24:37.032] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:37.032] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:17:0) after 1m0s
[08:25:37.075] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:17:0)
[08:25:37.075] Timer tick, asking Buildbucket for the build status
[08:25:37.205] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:37.205] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:18:0) after 1m0s
[08:26:37.225] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:18:0)
[08:26:37.225] Timer tick, asking Buildbucket for the build status
[08:26:37.287] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:37.287] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:19:0) after 1m0s
[08:27:37.306] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:19:0)
[08:27:37.306] Timer tick, asking Buildbucket for the build status
[08:27:37.451] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:37.451] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:20:0) after 1m0s
[08:28:37.471] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:20:0)
[08:28:37.471] Timer tick, asking Buildbucket for the build status
[08:28:37.922] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:37.922] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:21:0) after 1m0s
[08:29:37.943] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:21:0)
[08:29:37.943] Timer tick, asking Buildbucket for the build status
[08:29:37.989] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:37.989] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:22:0) after 1m0s
[08:30:38.024] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:22:0)
[08:30:38.024] Timer tick, asking Buildbucket for the build status
[08:30:38.070] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:38.070] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:23:0) after 1m0s
[08:31:38.107] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:23:0)
[08:31:38.107] Timer tick, asking Buildbucket for the build status
[08:31:38.343] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:38.343] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:24:0) after 1m0s
[08:32:38.368] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:24:0)
[08:32:38.368] Timer tick, asking Buildbucket for the build status
[08:32:38.686] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:38.686] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:25:0) after 1m0s
[08:33:38.705] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:25:0)
[08:33:38.705] Timer tick, asking Buildbucket for the build status
[08:33:38.841] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:38.841] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:26:0) after 1m0s
[08:34:38.856] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:26:0)
[08:34:38.856] Timer tick, asking Buildbucket for the build status
[08:34:38.966] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:38.966] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:27:0) after 1m0s
[08:35:38.985] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:27:0)
[08:35:38.985] Timer tick, asking Buildbucket for the build status
[08:35:39.042] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:39.042] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:28:0) after 1m0s
[08:36:39.066] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:28:0)
[08:36:39.066] Timer tick, asking Buildbucket for the build status
[08:36:39.116] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:39.116] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:29:0) after 1m0s
[08:37:39.134] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:29:0)
[08:37:39.134] Timer tick, asking Buildbucket for the build status
[08:37:39.200] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:39.200] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:30:0) after 1m0s
[08:38:39.219] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:30:0)
[08:38:39.219] Timer tick, asking Buildbucket for the build status
[08:38:39.267] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:39.267] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:31:0) after 1m0s
[08:39:39.287] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:31:0)
[08:39:39.287] Timer tick, asking Buildbucket for the build status
[08:39:39.496] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:39.496] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:32:0) after 1m0s
[08:40:39.514] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:32:0)
[08:40:39.514] Timer tick, asking Buildbucket for the build status
[08:40:39.612] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:39.612] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:33:0) after 1m0s
[08:41:39.630] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:33:0)
[08:41:39.630] Timer tick, asking Buildbucket for the build status
[08:41:39.700] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:39.700] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:34:0) after 1m0s
[08:42:39.761] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:34:0)
[08:42:39.761] Timer tick, asking Buildbucket for the build status
[08:42:39.842] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:39.842] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:35:0) after 1m0s
[08:43:39.859] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:35:0)
[08:43:39.859] Timer tick, asking Buildbucket for the build status
[08:43:40.075] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:40.075] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:36:0) after 1m0s
[08:44:40.104] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:36:0)
[08:44:40.104] Timer tick, asking Buildbucket for the build status
[08:44:40.160] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:40.160] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:37:0) after 1m0s
[08:45:40.183] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:37:0)
[08:45:40.183] Timer tick, asking Buildbucket for the build status
[08:45:40.248] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:40.248] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:38:0) after 1m0s
[08:46:40.307] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:38:0)
[08:46:40.307] Timer tick, asking Buildbucket for the build status
[08:46:40.348] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:40.348] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:39:0) after 1m0s
[08:47:40.400] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:39:0)
[08:47:40.400] Timer tick, asking Buildbucket for the build status
[08:47:40.557] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:40.557] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:40:0) after 1m0s
[08:48:40.581] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:40:0)
[08:48:40.581] Timer tick, asking Buildbucket for the build status
[08:48:40.628] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:40.628] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:41:0) after 1m0s
[08:49:40.652] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:41:0)
[08:49:40.652] Timer tick, asking Buildbucket for the build status
[08:49:40.727] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:40.727] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:42:0) after 1m0s
[08:50:40.746] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:42:0)
[08:50:40.746] Timer tick, asking Buildbucket for the build status
[08:50:40.956] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:40.956] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:43:0) after 1m0s
[08:51:40.980] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:43:0)
[08:51:40.980] Timer tick, asking Buildbucket for the build status
[08:51:41.120] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:41.120] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:44:0) after 1m0s
[08:52:41.151] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:44:0)
[08:52:41.151] Timer tick, asking Buildbucket for the build status
[08:52:41.224] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:41.224] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:45:0) after 1m0s
[08:53:41.243] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:45:0)
[08:53:41.243] Timer tick, asking Buildbucket for the build status
[08:53:41.341] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:41.341] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:46:0) after 1m0s
[08:54:41.365] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:46:0)
[08:54:41.365] Timer tick, asking Buildbucket for the build status
[08:54:41.554] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:41.554] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:47:0) after 1m0s
[08:55:41.577] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:47:0)
[08:55:41.577] Timer tick, asking Buildbucket for the build status
[08:55:41.633] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:41.633] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:48:0) after 1m0s
[08:56:41.653] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:48:0)
[08:56:41.653] Timer tick, asking Buildbucket for the build status
[08:56:41.854] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:41.854] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:49:0) after 1m0s
[08:57:41.872] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:49:0)
[08:57:41.872] Timer tick, asking Buildbucket for the build status
[08:57:41.909] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:41.909] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:50:0) after 1m0s
[08:58:41.930] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:50:0)
[08:58:41.930] Timer tick, asking Buildbucket for the build status
[08:58:42.003] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:42.003] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:51:0) after 1m0s
[08:59:42.044] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:51:0)
[08:59:42.044] Timer tick, asking Buildbucket for the build status
[08:59:42.272] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:42.272] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:52:0) after 1m0s
[09:00:42.289] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:52:0)
[09:00:42.289] Timer tick, asking Buildbucket for the build status
[09:00:42.360] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:42.360] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:53:0) after 1m0s
[09:01:42.387] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:53:0)
[09:01:42.387] Timer tick, asking Buildbucket for the build status
[09:01:42.481] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:42.481] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:54:0) after 1m0s
[09:02:42.526] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:54:0)
[09:02:42.526] Timer tick, asking Buildbucket for the build status
[09:02:42.635] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:42.635] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:55:0) after 1m0s
[09:03:42.656] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:55:0)
[09:03:42.656] Timer tick, asking Buildbucket for the build status
[09:03:42.709] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:42.709] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:56:0) after 1m0s
[09:04:42.746] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:56:0)
[09:04:42.746] Timer tick, asking Buildbucket for the build status
[09:04:42.814] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:42.814] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:57:0) after 1m0s
[09:05:42.833] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:57:0)
[09:05:42.833] Timer tick, asking Buildbucket for the build status
[09:05:42.900] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:42.900] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:58:0) after 1m0s
[09:06:42.922] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:58:0)
[09:06:42.922] Timer tick, asking Buildbucket for the build status
[09:06:43.068] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:43.068] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:59:0) after 1m0s
[09:07:43.104] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:59:0)
[09:07:43.104] Timer tick, asking Buildbucket for the build status
[09:07:43.165] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:43.165] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:60:0) after 1m0s
[09:08:43.186] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:60:0)
[09:08:43.186] Timer tick, asking Buildbucket for the build status
[09:08:43.261] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:43.261] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:61:0) after 1m0s
[09:09:43.294] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:61:0)
[09:09:43.294] Timer tick, asking Buildbucket for the build status
[09:09:43.345] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:43.345] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:62:0) after 1m0s
[09:10:43.365] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:62:0)
[09:10:43.365] Timer tick, asking Buildbucket for the build status
[09:10:43.520] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:43.520] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:63:0) after 1m0s
[09:11:43.540] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:63:0)
[09:11:43.540] Timer tick, asking Buildbucket for the build status
[09:11:43.594] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:43.594] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:64:0) after 1m0s
[09:12:43.613] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:64:0)
[09:12:43.613] Timer tick, asking Buildbucket for the build status
[09:12:43.681] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:43.681] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:65:0) after 1m0s
[09:13:43.700] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:65:0)
[09:13:43.700] Timer tick, asking Buildbucket for the build status
[09:13:43.758] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:43.758] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:66:0) after 1m0s
[09:14:43.777] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:66:0)
[09:14:43.777] Timer tick, asking Buildbucket for the build status
[09:14:43.847] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:43.847] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:67:0) after 1m0s
[09:15:43.888] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:67:0)
[09:15:43.888] Timer tick, asking Buildbucket for the build status
[09:15:43.932] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:43.933] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:68:0) after 1m0s
[09:16:43.951] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:68:0)
[09:16:43.951] Timer tick, asking Buildbucket for the build status
[09:16:44.010] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:44.010] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:69:0) after 1m0s
[09:17:44.029] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:69:0)
[09:17:44.029] Timer tick, asking Buildbucket for the build status
[09:17:44.074] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:44.074] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:70:0) after 1m0s
[09:18:44.258] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:70:0)
[09:18:44.258] Timer tick, asking Buildbucket for the build status
[09:18:44.332] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:44.332] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:71:0) after 1m0s
[09:19:44.374] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:71:0)
[09:19:44.374] Timer tick, asking Buildbucket for the build status
[09:19:44.420] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:44.420] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:72:0) after 1m0s
[09:20:44.466] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:72:0)
[09:20:44.466] Timer tick, asking Buildbucket for the build status
[09:20:44.508] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:44.508] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:73:0) after 1m0s
[09:21:44.588] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:73:0)
[09:21:44.588] Timer tick, asking Buildbucket for the build status
[09:21:44.667] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:44.667] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:74:0) after 1m0s
[09:22:44.688] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:74:0)
[09:22:44.688] Timer tick, asking Buildbucket for the build status
[09:22:44.731] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:44.731] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:75:0) after 1m0s
[09:23:44.768] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:75:0)
[09:23:44.768] Timer tick, asking Buildbucket for the build status
[09:23:44.823] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:44.823] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:76:0) after 1m0s
[09:24:44.841] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:76:0)
[09:24:44.841] Timer tick, asking Buildbucket for the build status
[09:24:44.928] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:44.928] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:77:0) after 1m0s
[09:25:44.947] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:77:0)
[09:25:44.947] Timer tick, asking Buildbucket for the build status
[09:25:45.000] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:45.000] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:78:0) after 1m0s
[09:26:45.038] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:78:0)
[09:26:45.038] Timer tick, asking Buildbucket for the build status
[09:26:45.116] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:45.116] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:79:0) after 1m0s
[09:27:45.155] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:79:0)
[09:27:45.155] Timer tick, asking Buildbucket for the build status
[09:27:45.206] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:45.206] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:80:0) after 1m0s
[09:28:45.234] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:80:0)
[09:28:45.234] Timer tick, asking Buildbucket for the build status
[09:28:45.464] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:45.464] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:81:0) after 1m0s
[09:29:45.504] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:81:0)
[09:29:45.504] Timer tick, asking Buildbucket for the build status
[09:29:45.555] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:45.555] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:82:0) after 1m0s
[09:30:45.574] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:82:0)
[09:30:45.574] Timer tick, asking Buildbucket for the build status
[09:30:45.655] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:45.655] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:83:0) after 1m0s
[09:31:45.684] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:83:0)
[09:31:45.684] Timer tick, asking Buildbucket for the build status
[09:31:45.732] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:45.732] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:84:0) after 1m0s
[09:32:45.753] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:84:0)
[09:32:45.753] Timer tick, asking Buildbucket for the build status
[09:32:45.895] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:45.895] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:85:0) after 1m0s
[09:33:45.984] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:85:0)
[09:33:45.984] Timer tick, asking Buildbucket for the build status
[09:33:46.029] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:46.029] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:86:0) after 1m0s
[09:34:46.055] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:86:0)
[09:34:46.055] Timer tick, asking Buildbucket for the build status
[09:34:46.124] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:46.124] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:87:0) after 1m0s
[09:35:46.147] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:87:0)
[09:35:46.147] Timer tick, asking Buildbucket for the build status
[09:35:46.192] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:46.192] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:88:0) after 1m0s
[09:36:46.223] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:88:0)
[09:36:46.223] Timer tick, asking Buildbucket for the build status
[09:36:46.313] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:46.313] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:89:0) after 1m0s
[09:37:46.480] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:89:0)
[09:37:46.480] Timer tick, asking Buildbucket for the build status
[09:37:46.524] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:46.524] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:90:0) after 1m0s
[09:38:46.545] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:90:0)
[09:38:46.545] Timer tick, asking Buildbucket for the build status
[09:38:46.786] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:46.786] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:91:0) after 1m0s
[09:39:46.805] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:91:0)
[09:39:46.805] Timer tick, asking Buildbucket for the build status
[09:39:46.847] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:46.847] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:92:0) after 1m0s
[09:40:46.867] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:92:0)
[09:40:46.867] Timer tick, asking Buildbucket for the build status
[09:40:46.972] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:46.972] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:93:0) after 1m0s
[09:41:47.001] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:93:0)
[09:41:47.001] Timer tick, asking Buildbucket for the build status
[09:41:47.047] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:47.047] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:94:0) after 1m0s
[09:42:47.066] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:94:0)
[09:42:47.066] Timer tick, asking Buildbucket for the build status
[09:42:47.133] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:47.133] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:95:0) after 1m0s
[09:43:47.155] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:95:0)
[09:43:47.155] Timer tick, asking Buildbucket for the build status
[09:43:47.202] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:47.202] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:96:0) after 1m0s
[09:44:47.229] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:96:0)
[09:44:47.229] Timer tick, asking Buildbucket for the build status
[09:44:47.339] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:47.339] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:97:0) after 1m0s
[09:45:47.358] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:97:0)
[09:45:47.358] Timer tick, asking Buildbucket for the build status
[09:45:47.415] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:47.415] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:98:0) after 1m0s
[09:46:47.442] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:98:0)
[09:46:47.442] Timer tick, asking Buildbucket for the build status
[09:46:47.566] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:47.567] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:99:0) after 1m0s
[09:47:47.590] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:99:0)
[09:47:47.590] Timer tick, asking Buildbucket for the build status
[09:47:47.696] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:47.696] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:100:0) after 1m0s
[09:48:47.725] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:100:0)
[09:48:47.725] Timer tick, asking Buildbucket for the build status
[09:48:47.777] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:47.777] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:101:0) after 1m0s
[09:49:47.813] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:101:0)
[09:49:47.813] Timer tick, asking Buildbucket for the build status
[09:49:47.901] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:47.901] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:102:0) after 1m0s
[09:50:47.923] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:102:0)
[09:50:47.923] Timer tick, asking Buildbucket for the build status
[09:50:48.080] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:48.080] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:103:0) after 1m0s
[09:51:48.103] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:103:0)
[09:51:48.103] Timer tick, asking Buildbucket for the build status
[09:51:48.193] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:48.193] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:104:0) after 1m0s
[09:52:48.220] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:104:0)
[09:52:48.220] Timer tick, asking Buildbucket for the build status
[09:52:48.269] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:48.269] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:105:0) after 1m0s
[09:53:48.306] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:105:0)
[09:53:48.306] Timer tick, asking Buildbucket for the build status
[09:53:48.389] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:48.389] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:106:0) after 1m0s
[09:54:48.410] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:106:0)
[09:54:48.410] Timer tick, asking Buildbucket for the build status
[09:54:48.731] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:48.731] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:107:0) after 1m0s
[09:55:48.749] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:107:0)
[09:55:48.749] Timer tick, asking Buildbucket for the build status
[09:55:48.859] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:48.859] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:108:0) after 1m0s
[09:56:48.882] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:108:0)
[09:56:48.882] Timer tick, asking Buildbucket for the build status
[09:56:48.944] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:48.944] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:109:0) after 1m0s
[09:57:48.964] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:109:0)
[09:57:48.964] Timer tick, asking Buildbucket for the build status
[09:57:49.037] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:49.037] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:110:0) after 1m0s
[09:58:49.059] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:110:0)
[09:58:49.059] Timer tick, asking Buildbucket for the build status
[09:58:49.284] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:49.284] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:111:0) after 1m0s
[09:59:49.333] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:111:0)
[09:59:49.333] Timer tick, asking Buildbucket for the build status
[09:59:49.377] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:49.377] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:112:0) after 1m0s
[10:00:49.417] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:112:0)
[10:00:49.417] Timer tick, asking Buildbucket for the build status
[10:00:49.502] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:49.502] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:113:0) after 1m0s
[10:01:49.523] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:113:0)
[10:01:49.523] Timer tick, asking Buildbucket for the build status
[10:01:49.593] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:49.593] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:114:0) after 1m0s
[10:02:49.610] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:114:0)
[10:02:49.610] Timer tick, asking Buildbucket for the build status
[10:02:49.954] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:49.954] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:115:0) after 1m0s
[10:03:49.998] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:115:0)
[10:03:49.998] Timer tick, asking Buildbucket for the build status
[10:03:50.249] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:50.249] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:116:0) after 1m0s
[10:04:50.273] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:116:0)
[10:04:50.273] Timer tick, asking Buildbucket for the build status
[10:04:50.362] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:50.362] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:117:0) after 1m0s
[10:05:50.392] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:117:0)
[10:05:50.392] Timer tick, asking Buildbucket for the build status
[10:05:50.455] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:50.455] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:118:0) after 1m0s
[10:06:50.498] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:118:0)
[10:06:50.498] Timer tick, asking Buildbucket for the build status
[10:06:50.678] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:50.678] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:119:0) after 1m0s
[10:07:50.716] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:119:0)
[10:07:50.716] Timer tick, asking Buildbucket for the build status
[10:07:50.800] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:50.800] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:120:0) after 1m0s
[10:08:50.833] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:120:0)
[10:08:50.833] Timer tick, asking Buildbucket for the build status
[10:08:51.169] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:51.169] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:121:0) after 1m0s
[10:09:51.192] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:121:0)
[10:09:51.192] Timer tick, asking Buildbucket for the build status
[10:09:51.246] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:51.246] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:122:0) after 1m0s
[10:10:51.272] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:122:0)
[10:10:51.272] Timer tick, asking Buildbucket for the build status
[10:10:51.342] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:51.342] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:123:0) after 1m0s
[10:11:51.404] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:123:0)
[10:11:51.404] Timer tick, asking Buildbucket for the build status
[10:11:51.681] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:51.681] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:124:0) after 1m0s
[10:12:51.706] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:124:0)
[10:12:51.706] Timer tick, asking Buildbucket for the build status
[10:12:51.749] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:51.749] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:125:0) after 1m0s
[10:13:51.772] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:125:0)
[10:13:51.772] Timer tick, asking Buildbucket for the build status
[10:13:51.839] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:51.839] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:126:0) after 1m0s
[10:14:51.865] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:126:0)
[10:14:51.866] Timer tick, asking Buildbucket for the build status
[10:14:52.019] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:52.019] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:127:0) after 1m0s
[10:15:52.067] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:127:0)
[10:15:52.067] Timer tick, asking Buildbucket for the build status
[10:15:52.118] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:52.118] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:128:0) after 1m0s
[10:16:52.141] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:128:0)
[10:16:52.141] Timer tick, asking Buildbucket for the build status
[10:16:52.212] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:52.212] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:129:0) after 1m0s
[10:17:52.252] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:129:0)
[10:17:52.252] Timer tick, asking Buildbucket for the build status
[10:17:52.302] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:52.302] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:130:0) after 1m0s
[10:18:52.335] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:130:0)
[10:18:52.335] Timer tick, asking Buildbucket for the build status
[10:18:52.394] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:52.394] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:131:0) after 1m0s
[10:19:52.415] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:131:0)
[10:19:52.415] Timer tick, asking Buildbucket for the build status
[10:19:52.495] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:52.495] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:132:0) after 1m0s
[10:20:52.525] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:132:0)
[10:20:52.525] Timer tick, asking Buildbucket for the build status
[10:20:52.574] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:52.574] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:133:0) after 1m0s
[10:21:52.668] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:133:0)
[10:21:52.668] Timer tick, asking Buildbucket for the build status
[10:21:52.738] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:52.738] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:134:0) after 1m0s
[10:22:52.758] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:134:0)
[10:22:52.758] Timer tick, asking Buildbucket for the build status
[10:22:52.804] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:52.804] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:135:0) after 1m0s
[10:23:52.832] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:135:0)
[10:23:52.832] Timer tick, asking Buildbucket for the build status
[10:23:52.871] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:52.871] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:136:0) after 1m0s
[10:24:52.892] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:136:0)
[10:24:52.892] Timer tick, asking Buildbucket for the build status
[10:24:52.969] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:52.969] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:137:0) after 1m0s
[10:25:52.990] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:137:0)
[10:25:52.990] Timer tick, asking Buildbucket for the build status
[10:25:53.037] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:53.037] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:138:0) after 1m0s
[10:26:53.058] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:138:0)
[10:26:53.058] Timer tick, asking Buildbucket for the build status
[10:26:53.186] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:53.186] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:139:0) after 1m0s
[10:27:53.206] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:139:0)
[10:27:53.206] Timer tick, asking Buildbucket for the build status
[10:27:53.254] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:53.254] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:140:0) after 1m0s
[10:28:53.279] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:140:0)
[10:28:53.279] Timer tick, asking Buildbucket for the build status
[10:28:53.381] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:53.381] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:141:0) after 1m0s
[10:29:53.403] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:141:0)
[10:29:53.403] Timer tick, asking Buildbucket for the build status
[10:29:53.541] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:53.541] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:142:0) after 1m0s
[10:30:53.565] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:142:0)
[10:30:53.565] Timer tick, asking Buildbucket for the build status
[10:30:53.612] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:53.612] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:143:0) after 1m0s
[10:31:53.638] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:143:0)
[10:31:53.638] Timer tick, asking Buildbucket for the build status
[10:31:53.952] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:53.952] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:144:0) after 1m0s
[10:32:53.983] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:144:0)
[10:32:53.983] Timer tick, asking Buildbucket for the build status
[10:32:54.049] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:54.049] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:145:0) after 1m0s
[10:33:54.072] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:145:0)
[10:33:54.072] Timer tick, asking Buildbucket for the build status
[10:33:54.229] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:54.229] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:146:0) after 1m0s
[10:34:54.252] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:146:0)
[10:34:54.252] Timer tick, asking Buildbucket for the build status
[10:34:54.314] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:54.314] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:147:0) after 1m0s
[10:35:54.363] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:147:0)
[10:35:54.363] Timer tick, asking Buildbucket for the build status
[10:35:54.413] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:54.413] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:148:0) after 1m0s
[10:36:54.491] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:148:0)
[10:36:54.491] Timer tick, asking Buildbucket for the build status
[10:36:54.597] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:54.597] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:149:0) after 1m0s
[10:37:54.618] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:149:0)
[10:37:54.618] Timer tick, asking Buildbucket for the build status
[10:37:54.686] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:54.686] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:150:0) after 1m0s
[10:38:54.708] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:150:0)
[10:38:54.708] Timer tick, asking Buildbucket for the build status
[10:38:54.818] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:54.818] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:151:0) after 1m0s
[10:39:54.842] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:151:0)
[10:39:54.842] Timer tick, asking Buildbucket for the build status
[10:39:54.889] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:54.889] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:152:0) after 1m0s
[10:40:54.964] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:152:0)
[10:40:54.964] Timer tick, asking Buildbucket for the build status
[10:40:55.160] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:55.160] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:153:0) after 1m0s
[10:41:55.200] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:153:0)
[10:41:55.200] Timer tick, asking Buildbucket for the build status
[10:41:55.257] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:55.257] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:154:0) after 1m0s
[10:42:55.280] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:154:0)
[10:42:55.280] Timer tick, asking Buildbucket for the build status
[10:42:55.349] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:55.349] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:155:0) after 1m0s
[10:43:55.373] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:155:0)
[10:43:55.373] Timer tick, asking Buildbucket for the build status
[10:43:55.458] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:55.458] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:156:0) after 1m0s
[10:44:55.483] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:156:0)
[10:44:55.483] Timer tick, asking Buildbucket for the build status
[10:44:55.523] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:55.523] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:157:0) after 1m0s
[10:45:55.629] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:157:0)
[10:45:55.629] Timer tick, asking Buildbucket for the build status
[10:45:55.674] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:55.674] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:158:0) after 1m0s
[10:46:55.692] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:158:0)
[10:46:55.692] Timer tick, asking Buildbucket for the build status
[10:46:55.763] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:55.763] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:159:0) after 1m0s
[10:47:55.803] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:159:0)
[10:47:55.803] Timer tick, asking Buildbucket for the build status
[10:47:56.166] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:56.166] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:160:0) after 1m0s
[10:48:56.193] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:160:0)
[10:48:56.193] Timer tick, asking Buildbucket for the build status
[10:48:56.311] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:56.311] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:161:0) after 1m0s
[10:49:56.332] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:161:0)
[10:49:56.332] Timer tick, asking Buildbucket for the build status
[10:49:56.387] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:56.387] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:162:0) after 1m0s
[10:50:56.408] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:162:0)
[10:50:56.408] Timer tick, asking Buildbucket for the build status
[10:50:56.463] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:56.463] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:163:0) after 1m0s
[10:51:56.499] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:163:0)
[10:51:56.499] Timer tick, asking Buildbucket for the build status
[10:51:56.677] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:56.677] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:164:0) after 1m0s
[10:52:56.713] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:164:0)
[10:52:56.713] Timer tick, asking Buildbucket for the build status
[10:52:56.862] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:56.862] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:165:0) after 1m0s
[10:53:56.883] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:165:0)
[10:53:56.883] Timer tick, asking Buildbucket for the build status
[10:53:56.935] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:56.935] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:166:0) after 1m0s
[10:54:56.957] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:166:0)
[10:54:56.957] Timer tick, asking Buildbucket for the build status
[10:54:57.079] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:57.079] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:167:0) after 1m0s
[10:55:57.108] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:167:0)
[10:55:57.108] Timer tick, asking Buildbucket for the build status
[10:55:57.157] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:57.157] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:168:0) after 1m0s
[10:56:57.183] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:168:0)
[10:56:57.183] Timer tick, asking Buildbucket for the build status
[10:56:57.260] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:57.260] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:169:0) after 1m0s
[10:57:57.283] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:169:0)
[10:57:57.283] Timer tick, asking Buildbucket for the build status
[10:57:57.330] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:57.330] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:170:0) after 1m0s
[10:58:57.352] Handling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:170:0)
[10:58:57.352] Timer tick, asking Buildbucket for the build status
[10:58:57.402] Build 8921019095887972864: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:57.402] Scheduling timer "check-buildbucket-build-status" (r8/archive:9086449158249503280:171:0) after 1m0s
[10:59:41.379] Received PubSub notification, asking Buildbucket for the build status
[10:59:41.452] Build 8921019095887972864: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:59:41.452] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "AUTO",
"completed_ts": "1550660380220402",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1550650234289425",
"id": "8921019095887972864",
"parameters_json": "{\"builder_name\": \"archive\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8.git\", \"revision\": \"b2d27c4647f5f7b5c47087532e94940d21143f70\"}}",
"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\": [\"4113-bca9810\"], \"machine_type\": [\"n1-standard-8\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"id\": [\"gce-xenial-396d8f4b-us-east1-b-7q1q\"], \"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\": 10, \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"archive\", \"key\": \"builder\"}, {\"value\": \"commit/git/b2d27c4647f5f7b5c47087532e94940d21143f70\", \"key\": \"buildset\"}, {\"value\": \"commit/gitiles/r8.googlesource.com/r8/+/b2d27c4647f5f7b5c47087532e94940d21143f70\", \"key\": \"buildset\"}, {\"value\": \"9086449158249503280\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"r8/archive\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"r8\", \"builder\": \"archive\", \"bucket\": \"ci\"}, \"number\": 10, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"3dab7a70bce848a6486670afc32d65ade65b8058\"}, \"recipe\": {\"name\": \"rex\", \"cipdPackage\": \"infra_internal/recipe_bundles/chrome-internal.googlesource.com/chrome/tools/build_limited/scripts/slave\"}, \"swarming\": {\"hostname\": \"chrome-swarming.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8921019095887972864\", \"input\": {\"gitilesCommit\": {\"project\": \"r8\", \"host\": \"r8.googlesource.com\", \"ref\": \"refs/heads/master\", \"id\": \"b2d27c4647f5f7b5c47087532e94940d21143f70\"}}, \"createTime\": \"2019-02-20T08:10:34.289425Z\"}}, \"repository\": \"https://r8.googlesource.com/r8.git\", \"buildername\": \"archive\", \"recipe\": \"rex\", \"mastername\": \"internal.client.r8\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1550650234289425, \"tags\": [\"build_address:luci.r8.ci/archive/10\", \"builder:archive\", \"buildset:commit/git/b2d27c4647f5f7b5c47087532e94940d21143f70\", \"buildset:commit/gitiles/r8.googlesource.com/r8/+/b2d27c4647f5f7b5c47087532e94940d21143f70\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9086449158249503280\", \"scheduler_job_id:r8/archive\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.r8.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"r8\", \"id\": \"8921019095887972864\"}}, \"branch\": \"refs/heads/master\", \"path_config\": \"generic\", \"revision\": \"b2d27c4647f5f7b5c47087532e94940d21143f70\", \"bot_id\": \"gce-xenial-396d8f4b-us-east1-b-7q1q\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1550650289363875",
"status": "COMPLETED",
"status_changed_ts": "1550660380975860",
"tags": [
"build_address:luci.r8.ci/archive/10",
"builder:archive",
"buildset:commit/git/b2d27c4647f5f7b5c47087532e94940d21143f70",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/b2d27c4647f5f7b5c47087532e94940d21143f70",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9086449158249503280",
"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/8921019095887972864/+/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:4322c65f4843cd10",
"user_agent:luci-scheduler"
],
"updated_ts": "1550660380979181",
"url": "https://ci.chromium.org/b/8921019095887972864",
"utcnow_ts": "1550660381442078"
}
}
[10:59:41.452] Invocation finished in 2h49m8.797522637s with status SUCCEEDED