[17:35:44.893] New invocation is queued and will start shortly
[17:35:48.459] Starting the invocation (attempt 1)
[17:35:48.494] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:35:48.494] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:35:48.494] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9068747190259316768",
"parameters_json": "{\"builder_name\":\"linux-internal\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://r8.googlesource.com/r8\",\"revision\":\"0fb0f526088608747abdf802cea363b07b13fb69\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@0fb0f526088608747abdf802cea363b07b13fb69\",\"title\":\"0fb0f526088608747abdf802cea363b07b13fb69\",\"url\":\"https://r8.googlesource.com/r8/+/0fb0f526088608747abdf802cea363b07b13fb69\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"0fb0f526088608747abdf802cea363b07b13fb69\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-internal",
"scheduler_invocation_id:9068747190259316768",
"scheduler_job_id:r8/linux-internal",
"user_agent:luci-scheduler",
"buildset:commit/git/0fb0f526088608747abdf802cea363b07b13fb69",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/0fb0f526088608747abdf802cea363b07b13fb69",
"gitiles_ref:refs/heads/master"
]
}
[17:35:49.607] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:r8",
"created_ts": "1567532148607460",
"id": "8903317125700972480",
"parameters_json": "{\"builder_name\": \"linux-internal\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"0fb0f526088608747abdf802cea363b07b13fb69\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@0fb0f526088608747abdf802cea363b07b13fb69\", \"title\": \"0fb0f526088608747abdf802cea363b07b13fb69\", \"url\": \"https://r8.googlesource.com/r8/+/0fb0f526088608747abdf802cea363b07b13fb69\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"0fb0f526088608747abdf802cea363b07b13fb69\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1567532149444064",
"tags": [
"build_address:luci.r8.ci/linux-internal/547",
"builder:linux-internal",
"buildset:commit/git/0fb0f526088608747abdf802cea363b07b13fb69",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/0fb0f526088608747abdf802cea363b07b13fb69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068747190259316768",
"scheduler_job_id:r8/linux-internal",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8903317125700972480/+/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:",
"user_agent:luci-scheduler"
],
"updated_ts": "1567532149444357",
"url": "https://ci.chromium.org/b/8903317125700972480",
"utcnow_ts": "1567532149600221"
}
}
[17:35:49.607] Task URL: https://ci.chromium.org/b/8903317125700972480
[17:35:49.608] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:2:0) after 1m0s
[17:36:18.673] Received PubSub notification, asking Buildbucket for the build status
[17:36:18.764] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:49.781] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:2:0)
[17:36:49.781] Timer tick, asking Buildbucket for the build status
[17:36:49.922] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:49.922] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:4:0) after 1m0s
[17:37:50.011] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:4:0)
[17:37:50.011] Timer tick, asking Buildbucket for the build status
[17:37:50.554] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:50.554] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:5:0) after 1m0s
[17:38:50.668] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:5:0)
[17:38:50.668] Timer tick, asking Buildbucket for the build status
[17:38:51.157] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:51.157] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:6:0) after 1m0s
[17:39:51.186] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:6:0)
[17:39:51.186] Timer tick, asking Buildbucket for the build status
[17:39:51.487] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:51.487] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:7:0) after 1m0s
[17:40:51.593] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:7:0)
[17:40:51.593] Timer tick, asking Buildbucket for the build status
[17:40:51.892] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:51.892] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:8:0) after 1m0s
[17:41:51.904] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:8:0)
[17:41:51.904] Timer tick, asking Buildbucket for the build status
[17:41:52.132] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:52.133] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:9:0) after 1m0s
[17:42:52.303] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:9:0)
[17:42:52.303] Timer tick, asking Buildbucket for the build status
[17:42:54.533] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:54.533] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:10:0) after 1m0s
[17:43:54.846] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:10:0)
[17:43:54.846] Timer tick, asking Buildbucket for the build status
[17:43:54.955] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:54.955] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:11:0) after 1m0s
[17:44:56.485] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:11:0)
[17:44:56.485] Timer tick, asking Buildbucket for the build status
[17:44:57.807] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:57.807] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:12:0) after 1m0s
[17:45:58.294] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:12:0)
[17:45:58.294] Timer tick, asking Buildbucket for the build status
[17:45:58.831] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:58.831] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:13:0) after 1m0s
[17:46:59.224] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:13:0)
[17:46:59.224] Timer tick, asking Buildbucket for the build status
[17:46:59.510] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:59.510] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:14:0) after 1m0s
[17:47:59.598] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:14:0)
[17:47:59.598] Timer tick, asking Buildbucket for the build status
[17:48:00.599] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:00.599] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:15:0) after 1m0s
[17:49:00.623] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:15:0)
[17:49:00.623] Timer tick, asking Buildbucket for the build status
[17:49:00.819] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:00.819] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:16:0) after 1m0s
[17:50:00.927] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:16:0)
[17:50:00.927] Timer tick, asking Buildbucket for the build status
[17:50:01.257] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:01.257] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:17:0) after 1m0s
[17:51:01.293] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:17:0)
[17:51:01.293] Timer tick, asking Buildbucket for the build status
[17:51:01.676] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:01.676] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:18:0) after 1m0s
[17:52:01.800] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:18:0)
[17:52:01.800] Timer tick, asking Buildbucket for the build status
[17:52:02.110] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:02.110] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:19:0) after 1m0s
[17:53:02.192] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:19:0)
[17:53:02.192] Timer tick, asking Buildbucket for the build status
[17:53:02.487] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:02.487] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:20:0) after 1m0s
[17:54:02.577] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:20:0)
[17:54:02.577] Timer tick, asking Buildbucket for the build status
[17:54:02.801] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:02.801] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:21:0) after 1m0s
[17:55:03.010] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:21:0)
[17:55:03.010] Timer tick, asking Buildbucket for the build status
[17:55:03.217] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:03.217] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:22:0) after 1m0s
[17:56:03.283] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:22:0)
[17:56:03.283] Timer tick, asking Buildbucket for the build status
[17:56:03.540] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:03.540] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:23:0) after 1m0s
[17:57:03.575] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:23:0)
[17:57:03.575] Timer tick, asking Buildbucket for the build status
[17:57:04.817] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:04.817] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:24:0) after 1m0s
[17:58:04.922] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:24:0)
[17:58:04.922] Timer tick, asking Buildbucket for the build status
[17:58:05.110] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:05.110] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:25:0) after 1m0s
[17:59:05.120] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:25:0)
[17:59:05.120] Timer tick, asking Buildbucket for the build status
[17:59:05.959] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:05.959] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:26:0) after 1m0s
[18:00:06.013] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:26:0)
[18:00:06.013] Timer tick, asking Buildbucket for the build status
[18:00:06.417] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:06.417] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:27:0) after 1m0s
[18:01:06.527] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:27:0)
[18:01:06.527] Timer tick, asking Buildbucket for the build status
[18:01:07.139] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:07.139] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:28:0) after 1m0s
[18:02:07.167] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:28:0)
[18:02:07.167] Timer tick, asking Buildbucket for the build status
[18:02:07.547] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:07.547] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:29:0) after 1m0s
[18:03:07.678] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:29:0)
[18:03:07.678] Timer tick, asking Buildbucket for the build status
[18:03:07.781] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:07.781] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:30:0) after 1m0s
[18:04:07.837] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:30:0)
[18:04:07.837] Timer tick, asking Buildbucket for the build status
[18:04:07.998] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:07.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:31:0) after 1m0s
[18:05:08.055] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:31:0)
[18:05:08.055] Timer tick, asking Buildbucket for the build status
[18:05:08.455] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:08.455] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:32:0) after 1m0s
[18:06:08.474] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:32:0)
[18:06:08.474] Timer tick, asking Buildbucket for the build status
[18:06:08.611] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:08.611] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:33:0) after 1m0s
[18:07:08.632] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:33:0)
[18:07:08.632] Timer tick, asking Buildbucket for the build status
[18:07:09.397] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:09.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:34:0) after 1m0s
[18:08:09.372] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:34:0)
[18:08:09.372] Timer tick, asking Buildbucket for the build status
[18:08:09.652] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:09.652] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:35:0) after 1m0s
[18:09:09.643] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:35:0)
[18:09:09.643] Timer tick, asking Buildbucket for the build status
[18:09:10.822] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:10.822] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:36:0) after 1m0s
[18:10:10.842] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:36:0)
[18:10:10.842] Timer tick, asking Buildbucket for the build status
[18:10:11.525] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:11.525] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:37:0) after 1m0s
[18:11:11.628] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:37:0)
[18:11:11.628] Timer tick, asking Buildbucket for the build status
[18:11:11.983] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:11.983] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:38:0) after 1m0s
[18:12:12.077] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:38:0)
[18:12:12.077] Timer tick, asking Buildbucket for the build status
[18:12:12.297] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:12.297] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:39:0) after 1m0s
[18:13:12.337] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:39:0)
[18:13:12.337] Timer tick, asking Buildbucket for the build status
[18:13:13.497] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:13.497] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:40:0) after 1m0s
[18:14:13.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:40:0)
[18:14:13.567] Timer tick, asking Buildbucket for the build status
[18:14:14.760] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:14.760] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:41:0) after 1m0s
[18:15:14.776] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:41:0)
[18:15:14.776] Timer tick, asking Buildbucket for the build status
[18:15:16.156] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:16.156] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:42:0) after 1m0s
[18:16:16.221] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:42:0)
[18:16:16.221] Timer tick, asking Buildbucket for the build status
[18:16:16.383] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:16.383] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:43:0) after 1m0s
[18:17:16.499] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:43:0)
[18:17:16.499] Timer tick, asking Buildbucket for the build status
[18:17:16.641] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:16.641] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:44:0) after 1m0s
[18:18:16.696] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:44:0)
[18:18:16.696] Timer tick, asking Buildbucket for the build status
[18:18:16.943] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:16.943] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:45:0) after 1m0s
[18:19:17.033] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:45:0)
[18:19:17.033] Timer tick, asking Buildbucket for the build status
[18:19:17.349] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:17.350] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:46:0) after 1m0s
[18:20:17.389] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:46:0)
[18:20:17.389] Timer tick, asking Buildbucket for the build status
[18:20:27.044] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:27.044] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:47:0) after 1m0s
[18:21:27.397] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:47:0)
[18:21:27.398] Timer tick, asking Buildbucket for the build status
[18:21:29.159] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:29.159] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:48:0) after 1m0s
[18:22:29.443] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:48:0)
[18:22:29.443] Timer tick, asking Buildbucket for the build status
[18:22:29.709] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:29.709] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:49:0) after 1m0s
[18:23:29.916] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:49:0)
[18:23:29.916] Timer tick, asking Buildbucket for the build status
[18:23:30.563] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:30.563] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:50:0) after 1m0s
[18:24:30.715] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:50:0)
[18:24:30.715] Timer tick, asking Buildbucket for the build status
[18:24:31.520] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:31.520] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:51:0) after 1m0s
[18:25:31.683] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:51:0)
[18:25:31.683] Timer tick, asking Buildbucket for the build status
[18:25:32.127] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:32.127] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:52:0) after 1m0s
[18:26:32.188] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:52:0)
[18:26:32.188] Timer tick, asking Buildbucket for the build status
[18:26:32.948] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:32.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:53:0) after 1m0s
[18:27:33.207] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:53:0)
[18:27:33.207] Timer tick, asking Buildbucket for the build status
[18:27:33.375] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:33.375] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:54:0) after 1m0s
[18:28:33.397] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:54:0)
[18:28:33.397] Timer tick, asking Buildbucket for the build status
[18:28:34.484] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:34.485] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:55:0) after 1m0s
[18:29:34.555] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:55:0)
[18:29:34.555] Timer tick, asking Buildbucket for the build status
[18:29:35.834] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:35.834] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:56:0) after 1m0s
[18:30:35.955] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:56:0)
[18:30:35.955] Timer tick, asking Buildbucket for the build status
[18:30:36.172] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:36.172] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:57:0) after 1m0s
[18:31:36.395] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:57:0)
[18:31:36.395] Timer tick, asking Buildbucket for the build status
[18:31:36.779] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:36.779] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:58:0) after 1m0s
[18:32:36.832] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:58:0)
[18:32:36.832] Timer tick, asking Buildbucket for the build status
[18:32:37.039] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:37.039] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:59:0) after 1m0s
[18:33:37.115] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:59:0)
[18:33:37.115] Timer tick, asking Buildbucket for the build status
[18:33:37.302] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:37.302] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:60:0) after 1m0s
[18:34:37.552] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:60:0)
[18:34:37.552] Timer tick, asking Buildbucket for the build status
[18:34:37.712] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:37.712] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:61:0) after 1m0s
[18:35:37.958] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:61:0)
[18:35:37.958] Timer tick, asking Buildbucket for the build status
[18:35:39.464] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:39.464] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:62:0) after 1m0s
[18:36:39.459] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:62:0)
[18:36:39.459] Timer tick, asking Buildbucket for the build status
[18:36:39.635] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:39.635] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:63:0) after 1m0s
[18:37:39.723] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:63:0)
[18:37:39.723] Timer tick, asking Buildbucket for the build status
[18:37:39.946] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:39.946] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:64:0) after 1m0s
[18:38:39.936] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:64:0)
[18:38:39.936] Timer tick, asking Buildbucket for the build status
[18:38:40.088] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:40.088] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:65:0) after 1m0s
[18:39:40.126] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:65:0)
[18:39:40.126] Timer tick, asking Buildbucket for the build status
[18:39:40.462] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:40.462] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:66:0) after 1m0s
[18:40:40.477] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:66:0)
[18:40:40.477] Timer tick, asking Buildbucket for the build status
[18:40:41.075] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:41.075] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:67:0) after 1m0s
[18:41:41.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:67:0)
[18:41:41.099] Timer tick, asking Buildbucket for the build status
[18:41:41.553] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:41.553] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:68:0) after 1m0s
[18:42:41.703] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:68:0)
[18:42:41.703] Timer tick, asking Buildbucket for the build status
[18:42:42.075] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:42.075] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:69:0) after 1m0s
[18:43:42.221] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:69:0)
[18:43:42.221] Timer tick, asking Buildbucket for the build status
[18:43:42.866] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:42.866] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:70:0) after 1m0s
[18:44:43.253] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:70:0)
[18:44:43.253] Timer tick, asking Buildbucket for the build status
[18:44:44.072] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:44.072] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:71:0) after 1m0s
[18:45:44.732] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:71:0)
[18:45:44.732] Timer tick, asking Buildbucket for the build status
[18:45:44.908] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:44.909] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:72:0) after 1m0s
[18:46:45.319] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:72:0)
[18:46:45.319] Timer tick, asking Buildbucket for the build status
[18:46:45.575] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:45.575] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:73:0) after 1m0s
[18:47:45.748] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:73:0)
[18:47:45.748] Timer tick, asking Buildbucket for the build status
[18:47:45.869] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:45.869] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:74:0) after 1m0s
[18:48:46.023] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:74:0)
[18:48:46.023] Timer tick, asking Buildbucket for the build status
[18:48:46.370] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:46.370] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:75:0) after 1m0s
[18:49:46.644] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:75:0)
[18:49:46.644] Timer tick, asking Buildbucket for the build status
[18:49:46.776] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:46.776] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:76:0) after 1m0s
[18:50:46.873] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:76:0)
[18:50:46.873] Timer tick, asking Buildbucket for the build status
[18:50:47.269] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:47.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:77:0) after 1m0s
[18:51:47.349] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:77:0)
[18:51:47.349] Timer tick, asking Buildbucket for the build status
[18:51:47.511] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:47.511] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:78:0) after 1m0s
[18:52:47.593] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:78:0)
[18:52:47.593] Timer tick, asking Buildbucket for the build status
[18:52:48.020] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:48.020] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:79:0) after 1m0s
[18:53:48.303] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:79:0)
[18:53:48.303] Timer tick, asking Buildbucket for the build status
[18:53:48.627] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:48.627] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:80:0) after 1m0s
[18:54:48.647] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:80:0)
[18:54:48.647] Timer tick, asking Buildbucket for the build status
[18:54:48.907] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:48.907] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:81:0) after 1m0s
[18:55:48.980] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:81:0)
[18:55:48.980] Timer tick, asking Buildbucket for the build status
[18:55:49.238] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:49.238] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:82:0) after 1m0s
[18:56:49.270] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:82:0)
[18:56:49.270] Timer tick, asking Buildbucket for the build status
[18:56:49.612] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:49.612] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:83:0) after 1m0s
[18:57:49.620] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:83:0)
[18:57:49.620] Timer tick, asking Buildbucket for the build status
[18:57:50.016] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:50.016] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:84:0) after 1m0s
[18:58:50.289] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:84:0)
[18:58:50.289] Timer tick, asking Buildbucket for the build status
[18:58:50.824] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:50.824] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:85:0) after 1m0s
[18:59:50.847] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:85:0)
[18:59:50.847] Timer tick, asking Buildbucket for the build status
[18:59:51.397] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:51.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:86:0) after 1m0s
[19:00:51.460] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:86:0)
[19:00:51.460] Timer tick, asking Buildbucket for the build status
[19:00:51.696] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:51.696] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:87:0) after 1m0s
[19:01:51.793] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:87:0)
[19:01:51.793] Timer tick, asking Buildbucket for the build status
[19:01:52.047] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:52.048] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:88:0) after 1m0s
[19:02:52.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:88:0)
[19:02:52.099] Timer tick, asking Buildbucket for the build status
[19:02:53.298] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:53.298] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:89:0) after 1m0s
[19:03:53.332] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:89:0)
[19:03:53.332] Timer tick, asking Buildbucket for the build status
[19:03:53.535] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:53.535] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:90:0) after 1m0s
[19:04:53.597] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:90:0)
[19:04:53.597] Timer tick, asking Buildbucket for the build status
[19:04:54.210] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:54.210] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:91:0) after 1m0s
[19:05:54.240] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:91:0)
[19:05:54.240] Timer tick, asking Buildbucket for the build status
[19:05:54.470] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:54.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:92:0) after 1m0s
[19:06:54.593] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:92:0)
[19:06:54.593] Timer tick, asking Buildbucket for the build status
[19:06:55.062] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:55.062] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:93:0) after 1m0s
[19:07:55.102] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:93:0)
[19:07:55.102] Timer tick, asking Buildbucket for the build status
[19:07:55.579] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:55.579] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:94:0) after 1m0s
[19:08:55.578] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:94:0)
[19:08:55.578] Timer tick, asking Buildbucket for the build status
[19:08:56.451] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:56.451] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:95:0) after 1m0s
[19:09:56.454] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:95:0)
[19:09:56.454] Timer tick, asking Buildbucket for the build status
[19:09:56.602] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:56.602] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:96:0) after 1m0s
[19:10:56.694] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:96:0)
[19:10:56.694] Timer tick, asking Buildbucket for the build status
[19:10:57.639] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:57.639] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:97:0) after 1m0s
[19:11:57.660] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:97:0)
[19:11:57.660] Timer tick, asking Buildbucket for the build status
[19:11:58.107] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:58.107] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:98:0) after 1m0s
[19:12:58.131] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:98:0)
[19:12:58.131] Timer tick, asking Buildbucket for the build status
[19:12:58.355] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:58.355] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:99:0) after 1m0s
[19:13:58.392] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:99:0)
[19:13:58.392] Timer tick, asking Buildbucket for the build status
[19:13:58.537] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:58.537] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:100:0) after 1m0s
[19:14:58.593] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:100:0)
[19:14:58.593] Timer tick, asking Buildbucket for the build status
[19:14:59.009] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:59.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:101:0) after 1m0s
[19:15:59.005] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:101:0)
[19:15:59.005] Timer tick, asking Buildbucket for the build status
[19:16:00.744] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:00.744] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:102:0) after 1m0s
[19:17:00.759] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:102:0)
[19:17:00.759] Timer tick, asking Buildbucket for the build status
[19:17:00.910] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:00.910] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:103:0) after 1m0s
[19:18:00.969] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:103:0)
[19:18:00.969] Timer tick, asking Buildbucket for the build status
[19:18:01.164] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:01.164] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:104:0) after 1m0s
[19:19:01.274] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:104:0)
[19:19:01.274] Timer tick, asking Buildbucket for the build status
[19:19:01.656] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:01.656] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:105:0) after 1m0s
[19:20:01.766] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:105:0)
[19:20:01.766] Timer tick, asking Buildbucket for the build status
[19:20:01.928] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:01.928] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:106:0) after 1m0s
[19:21:01.985] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:106:0)
[19:21:01.985] Timer tick, asking Buildbucket for the build status
[19:21:02.181] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:02.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:107:0) after 1m0s
[19:22:02.219] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:107:0)
[19:22:02.219] Timer tick, asking Buildbucket for the build status
[19:22:03.327] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:03.327] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:108:0) after 1m0s
[19:23:03.331] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:108:0)
[19:23:03.331] Timer tick, asking Buildbucket for the build status
[19:23:03.635] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:03.635] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:109:0) after 1m0s
[19:24:03.771] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:109:0)
[19:24:03.771] Timer tick, asking Buildbucket for the build status
[19:24:04.086] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:04.086] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:110:0) after 1m0s
[19:25:04.138] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:110:0)
[19:25:04.138] Timer tick, asking Buildbucket for the build status
[19:25:04.248] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:04.248] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:111:0) after 1m0s
[19:26:04.286] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:111:0)
[19:26:04.287] Timer tick, asking Buildbucket for the build status
[19:26:06.985] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:06.985] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:112:0) after 1m0s
[19:27:07.018] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:112:0)
[19:27:07.018] Timer tick, asking Buildbucket for the build status
[19:27:07.440] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:07.440] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:113:0) after 1m0s
[19:28:07.499] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:113:0)
[19:28:07.499] Timer tick, asking Buildbucket for the build status
[19:28:07.733] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:07.733] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:114:0) after 1m0s
[19:29:07.758] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:114:0)
[19:29:07.759] Timer tick, asking Buildbucket for the build status
[19:29:07.881] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:07.881] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:115:0) after 1m0s
[19:30:07.959] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:115:0)
[19:30:07.959] Timer tick, asking Buildbucket for the build status
[19:30:08.450] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:08.450] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:116:0) after 1m0s
[19:31:08.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:116:0)
[19:31:08.567] Timer tick, asking Buildbucket for the build status
[19:31:09.017] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:09.017] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:117:0) after 1m0s
[19:32:09.084] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:117:0)
[19:32:09.084] Timer tick, asking Buildbucket for the build status
[19:32:09.397] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:09.397] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:118:0) after 1m0s
[19:33:09.536] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:118:0)
[19:33:09.536] Timer tick, asking Buildbucket for the build status
[19:33:09.643] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:09.643] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:119:0) after 1m0s
[19:34:09.704] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:119:0)
[19:34:09.704] Timer tick, asking Buildbucket for the build status
[19:34:10.157] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:10.157] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:120:0) after 1m0s
[19:35:10.133] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:120:0)
[19:35:10.133] Timer tick, asking Buildbucket for the build status
[19:35:10.237] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:10.237] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:121:0) after 1m0s
[19:36:10.222] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:121:0)
[19:36:10.222] Timer tick, asking Buildbucket for the build status
[19:36:10.456] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:10.456] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:122:0) after 1m0s
[19:37:10.606] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:122:0)
[19:37:10.606] Timer tick, asking Buildbucket for the build status
[19:37:11.050] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:11.050] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:123:0) after 1m0s
[19:38:11.099] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:123:0)
[19:38:11.099] Timer tick, asking Buildbucket for the build status
[19:38:11.234] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:11.235] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:124:0) after 1m0s
[19:39:11.298] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:124:0)
[19:39:11.298] Timer tick, asking Buildbucket for the build status
[19:39:12.318] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:12.318] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:125:0) after 1m0s
[19:40:12.376] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:125:0)
[19:40:12.376] Timer tick, asking Buildbucket for the build status
[19:40:13.825] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:13.825] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:126:0) after 1m0s
[19:41:13.957] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:126:0)
[19:41:13.957] Timer tick, asking Buildbucket for the build status
[19:41:14.237] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:14.237] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:127:0) after 1m0s
[19:42:14.266] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:127:0)
[19:42:14.266] Timer tick, asking Buildbucket for the build status
[19:42:14.481] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:14.481] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:128:0) after 1m0s
[19:43:14.537] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:128:0)
[19:43:14.537] Timer tick, asking Buildbucket for the build status
[19:43:14.657] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:14.657] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:129:0) after 1m0s
[19:44:14.703] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:129:0)
[19:44:14.703] Timer tick, asking Buildbucket for the build status
[19:44:15.428] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:15.428] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:130:0) after 1m0s
[19:45:15.592] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:130:0)
[19:45:15.592] Timer tick, asking Buildbucket for the build status
[19:45:15.756] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:15.756] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:131:0) after 1m0s
[19:46:15.778] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:131:0)
[19:46:15.778] Timer tick, asking Buildbucket for the build status
[19:46:16.269] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:16.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:132:0) after 1m0s
[19:47:16.294] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:132:0)
[19:47:16.295] Timer tick, asking Buildbucket for the build status
[19:47:16.525] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:16.525] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:133:0) after 1m0s
[19:48:16.546] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:133:0)
[19:48:16.546] Timer tick, asking Buildbucket for the build status
[19:48:16.664] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:16.664] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:134:0) after 1m0s
[19:49:16.701] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:134:0)
[19:49:16.701] Timer tick, asking Buildbucket for the build status
[19:49:17.241] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:17.241] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:135:0) after 1m0s
[19:50:17.248] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:135:0)
[19:50:17.248] Timer tick, asking Buildbucket for the build status
[19:50:17.376] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:17.376] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:136:0) after 1m0s
[19:51:17.430] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:136:0)
[19:51:17.430] Timer tick, asking Buildbucket for the build status
[19:51:17.569] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:17.569] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:137:0) after 1m0s
[19:52:17.581] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:137:0)
[19:52:17.581] Timer tick, asking Buildbucket for the build status
[19:52:17.852] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:17.852] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:138:0) after 1m0s
[19:53:17.977] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:138:0)
[19:53:17.977] Timer tick, asking Buildbucket for the build status
[19:53:18.202] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:18.202] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:139:0) after 1m0s
[19:54:18.275] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:139:0)
[19:54:18.275] Timer tick, asking Buildbucket for the build status
[19:54:18.492] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:18.492] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:140:0) after 1m0s
[19:55:18.514] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:140:0)
[19:55:18.514] Timer tick, asking Buildbucket for the build status
[19:55:18.981] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:18.981] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:141:0) after 1m0s
[19:56:19.010] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:141:0)
[19:56:19.010] Timer tick, asking Buildbucket for the build status
[19:56:19.181] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:19.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:142:0) after 1m0s
[19:57:19.227] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:142:0)
[19:57:19.227] Timer tick, asking Buildbucket for the build status
[19:57:19.444] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:19.444] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:143:0) after 1m0s
[19:58:19.481] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:143:0)
[19:58:19.481] Timer tick, asking Buildbucket for the build status
[19:58:19.794] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:19.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:144:0) after 1m0s
[19:59:19.833] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:144:0)
[19:59:19.833] Timer tick, asking Buildbucket for the build status
[19:59:19.932] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:19.932] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:145:0) after 1m0s
[20:00:19.958] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:145:0)
[20:00:19.958] Timer tick, asking Buildbucket for the build status
[20:00:20.424] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:20.424] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:146:0) after 1m0s
[20:01:20.510] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:146:0)
[20:01:20.510] Timer tick, asking Buildbucket for the build status
[20:01:20.869] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:20.869] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:147:0) after 1m0s
[20:02:21.005] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:147:0)
[20:02:21.005] Timer tick, asking Buildbucket for the build status
[20:02:21.160] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:21.160] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:148:0) after 1m0s
[20:03:21.194] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:148:0)
[20:03:21.194] Timer tick, asking Buildbucket for the build status
[20:03:21.348] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:21.348] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:149:0) after 1m0s
[20:04:21.371] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:149:0)
[20:04:21.371] Timer tick, asking Buildbucket for the build status
[20:04:21.519] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:21.519] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:150:0) after 1m0s
[20:05:21.559] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:150:0)
[20:05:21.559] Timer tick, asking Buildbucket for the build status
[20:05:21.702] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:21.702] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:151:0) after 1m0s
[20:06:21.792] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:151:0)
[20:06:21.792] Timer tick, asking Buildbucket for the build status
[20:06:22.054] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:22.054] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:152:0) after 1m0s
[20:07:22.813] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:152:0)
[20:07:22.813] Timer tick, asking Buildbucket for the build status
[20:07:23.207] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:23.207] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:153:0) after 1m0s
[20:08:23.193] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:153:0)
[20:08:23.193] Timer tick, asking Buildbucket for the build status
[20:08:24.101] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:24.101] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:154:0) after 1m0s
[20:09:24.146] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:154:0)
[20:09:24.146] Timer tick, asking Buildbucket for the build status
[20:09:24.393] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:24.393] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:155:0) after 1m0s
[20:10:24.503] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:155:0)
[20:10:24.503] Timer tick, asking Buildbucket for the build status
[20:10:24.697] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:24.697] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:156:0) after 1m0s
[20:11:24.734] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:156:0)
[20:11:24.734] Timer tick, asking Buildbucket for the build status
[20:11:25.072] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:25.072] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:157:0) after 1m0s
[20:12:25.154] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:157:0)
[20:12:25.154] Timer tick, asking Buildbucket for the build status
[20:12:25.481] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:25.481] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:158:0) after 1m0s
[20:13:25.559] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:158:0)
[20:13:25.559] Timer tick, asking Buildbucket for the build status
[20:13:25.974] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:25.974] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:159:0) after 1m0s
[20:14:26.001] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:159:0)
[20:14:26.001] Timer tick, asking Buildbucket for the build status
[20:14:26.185] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:26.185] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:160:0) after 1m0s
[20:15:26.211] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:160:0)
[20:15:26.211] Timer tick, asking Buildbucket for the build status
[20:15:26.598] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:26.599] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:161:0) after 1m0s
[20:16:26.626] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:161:0)
[20:16:26.626] Timer tick, asking Buildbucket for the build status
[20:16:26.977] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:26.977] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:162:0) after 1m0s
[20:17:27.082] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:162:0)
[20:17:27.082] Timer tick, asking Buildbucket for the build status
[20:17:27.328] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:27.328] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:163:0) after 1m0s
[20:18:27.709] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:163:0)
[20:18:27.709] Timer tick, asking Buildbucket for the build status
[20:18:32.729] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:32.729] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:164:0) after 1m0s
[20:19:32.960] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:164:0)
[20:19:32.960] Timer tick, asking Buildbucket for the build status
[20:19:33.072] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:33.072] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:165:0) after 1m0s
[20:20:33.271] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:165:0)
[20:20:33.271] Timer tick, asking Buildbucket for the build status
[20:20:33.751] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:33.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:166:0) after 1m0s
[20:21:33.895] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:166:0)
[20:21:33.895] Timer tick, asking Buildbucket for the build status
[20:21:34.125] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:34.125] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:167:0) after 1m0s
[20:22:34.379] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:167:0)
[20:22:34.379] Timer tick, asking Buildbucket for the build status
[20:22:34.692] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:22:34.692] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:168:0) after 1m0s
[20:23:34.739] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:168:0)
[20:23:34.739] Timer tick, asking Buildbucket for the build status
[20:23:35.076] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:23:35.076] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:169:0) after 1m0s
[20:24:35.115] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:169:0)
[20:24:35.115] Timer tick, asking Buildbucket for the build status
[20:24:35.332] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:24:35.332] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:170:0) after 1m0s
[20:25:35.368] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:170:0)
[20:25:35.368] Timer tick, asking Buildbucket for the build status
[20:25:35.577] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:25:35.577] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:171:0) after 1m0s
[20:26:35.650] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:171:0)
[20:26:35.650] Timer tick, asking Buildbucket for the build status
[20:26:35.805] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:26:35.805] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:172:0) after 1m0s
[20:27:35.952] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:172:0)
[20:27:35.952] Timer tick, asking Buildbucket for the build status
[20:27:36.084] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:27:36.084] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:173:0) after 1m0s
[20:28:36.219] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:173:0)
[20:28:36.219] Timer tick, asking Buildbucket for the build status
[20:28:37.584] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:28:37.584] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:174:0) after 1m0s
[20:29:37.660] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:174:0)
[20:29:37.660] Timer tick, asking Buildbucket for the build status
[20:29:38.468] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:29:38.468] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:175:0) after 1m0s
[20:30:38.510] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:175:0)
[20:30:38.510] Timer tick, asking Buildbucket for the build status
[20:30:39.013] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:30:39.013] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:176:0) after 1m0s
[20:31:39.120] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:176:0)
[20:31:39.120] Timer tick, asking Buildbucket for the build status
[20:31:39.236] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:31:39.236] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:177:0) after 1m0s
[20:32:39.320] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:177:0)
[20:32:39.320] Timer tick, asking Buildbucket for the build status
[20:32:39.643] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:32:39.643] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:178:0) after 1m0s
[20:33:39.726] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:178:0)
[20:33:39.726] Timer tick, asking Buildbucket for the build status
[20:33:41.066] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:33:41.066] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:179:0) after 1m0s
[20:34:41.094] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:179:0)
[20:34:41.094] Timer tick, asking Buildbucket for the build status
[20:34:41.182] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:34:41.182] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:180:0) after 1m0s
[20:35:41.194] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:180:0)
[20:35:41.194] Timer tick, asking Buildbucket for the build status
[20:35:41.335] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:35:41.335] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:181:0) after 1m0s
[20:36:41.369] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:181:0)
[20:36:41.369] Timer tick, asking Buildbucket for the build status
[20:36:41.756] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:36:41.756] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:182:0) after 1m0s
[20:37:41.791] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:182:0)
[20:37:41.791] Timer tick, asking Buildbucket for the build status
[20:37:41.950] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:37:41.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:183:0) after 1m0s
[20:38:41.988] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:183:0)
[20:38:41.988] Timer tick, asking Buildbucket for the build status
[20:38:42.122] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:38:42.122] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:184:0) after 1m0s
[20:39:42.159] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:184:0)
[20:39:42.159] Timer tick, asking Buildbucket for the build status
[20:39:44.149] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:39:44.149] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:185:0) after 1m0s
[20:40:44.283] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:185:0)
[20:40:44.283] Timer tick, asking Buildbucket for the build status
[20:40:44.475] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:40:44.475] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:186:0) after 1m0s
[20:41:44.530] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:186:0)
[20:41:44.530] Timer tick, asking Buildbucket for the build status
[20:41:44.704] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:41:44.704] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:187:0) after 1m0s
[20:42:44.763] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:187:0)
[20:42:44.763] Timer tick, asking Buildbucket for the build status
[20:42:44.866] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:42:44.866] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:188:0) after 1m0s
[20:43:44.913] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:188:0)
[20:43:44.913] Timer tick, asking Buildbucket for the build status
[20:43:45.245] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:43:45.245] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:189:0) after 1m0s
[20:44:45.294] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:189:0)
[20:44:45.294] Timer tick, asking Buildbucket for the build status
[20:44:45.602] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:44:45.602] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:190:0) after 1m0s
[20:45:45.635] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:190:0)
[20:45:45.635] Timer tick, asking Buildbucket for the build status
[20:45:45.840] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:45:45.840] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:191:0) after 1m0s
[20:46:45.865] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:191:0)
[20:46:45.865] Timer tick, asking Buildbucket for the build status
[20:46:46.394] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:46:46.394] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:192:0) after 1m0s
[20:47:46.431] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:192:0)
[20:47:46.431] Timer tick, asking Buildbucket for the build status
[20:47:46.589] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:47:46.589] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:193:0) after 1m0s
[20:48:46.610] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:193:0)
[20:48:46.610] Timer tick, asking Buildbucket for the build status
[20:48:46.841] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:48:46.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:194:0) after 1m0s
[20:49:46.872] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:194:0)
[20:49:46.872] Timer tick, asking Buildbucket for the build status
[20:49:47.118] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:49:47.118] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:195:0) after 1m0s
[20:50:47.106] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:195:0)
[20:50:47.106] Timer tick, asking Buildbucket for the build status
[20:50:47.219] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:50:47.219] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:196:0) after 1m0s
[20:51:47.285] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:196:0)
[20:51:47.285] Timer tick, asking Buildbucket for the build status
[20:51:48.076] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:51:48.076] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:197:0) after 1m0s
[20:52:48.107] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:197:0)
[20:52:48.107] Timer tick, asking Buildbucket for the build status
[20:52:48.293] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:52:48.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:198:0) after 1m0s
[20:53:48.426] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:198:0)
[20:53:48.426] Timer tick, asking Buildbucket for the build status
[20:53:48.577] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:48.577] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:199:0) after 1m0s
[20:54:48.630] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:199:0)
[20:54:48.630] Timer tick, asking Buildbucket for the build status
[20:54:48.851] Build 8903317125700972480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:48.851] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9068747190259316768:200:0) after 1m0s
[20:55:12.851] Received PubSub notification, asking Buildbucket for the build status
[20:55:12.989] Build 8903317125700972480: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[20:55:12.989] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1567544112197298",
"created_by": "project:r8",
"created_ts": "1567532148607460",
"failure_reason": "BUILD_FAILURE",
"id": "8903317125700972480",
"parameters_json": "{\"builder_name\": \"linux-internal\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"0fb0f526088608747abdf802cea363b07b13fb69\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@0fb0f526088608747abdf802cea363b07b13fb69\", \"title\": \"0fb0f526088608747abdf802cea363b07b13fb69\", \"url\": \"https://r8.googlesource.com/r8/+/0fb0f526088608747abdf802cea363b07b13fb69\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"0fb0f526088608747abdf802cea363b07b13fb69\"}}",
"project": "r8",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Run internal') (retcode: 1)\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"0fb0f526088608747abdf802cea363b07b13fb69\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@0fb0f526088608747abdf802cea363b07b13fb69\", \"title\": \"0fb0f526088608747abdf802cea363b07b13fb69\", \"url\": \"https://r8.googlesource.com/r8/+/0fb0f526088608747abdf802cea363b07b13fb69\"}]}, \"bot_id\": \"luci-r8-ci-internal-0-vpkn\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-internal\", \"buildnumber\": 547, \"internal\": \"True\", \"mastername\": \"internal.client.r8\", \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"0fb0f526088608747abdf802cea363b07b13fb69\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-r8-ci-internal-0-vpkn\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"internal\": [\"true\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.r8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4397-8786906\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Run internal') (retcode: 1)\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1567532175930025",
"status": "COMPLETED",
"status_changed_ts": "1567532176442982",
"tags": [
"build_address:luci.r8.ci/linux-internal/547",
"builder:linux-internal",
"buildset:commit/git/0fb0f526088608747abdf802cea363b07b13fb69",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/0fb0f526088608747abdf802cea363b07b13fb69",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9068747190259316768",
"scheduler_job_id:r8/linux-internal",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8903317125700972480/+/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:47110423a9295510",
"user_agent:luci-scheduler"
],
"updated_ts": "1567544112450636",
"url": "https://ci.chromium.org/b/8903317125700972480",
"utcnow_ts": "1567544112974488"
}
}
[20:55:12.989] Invocation finished in 3h19m28.113285183s with status FAILED