[06:49:28.916] New invocation is queued and will start shortly
[06:49:30.170] Starting the invocation (attempt 1)
[06:49:30.222] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:49:30.222] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:49:30.222] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9047678756645272288",
"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\":\"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@3c4406b84a9cc772a5e957905047c1a1c0a2d135\",\"title\":\"3c4406b84a9cc772a5e957905047c1a1c0a2d135\",\"url\":\"https://r8.googlesource.com/r8/+/3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-internal",
"scheduler_invocation_id:9047678756645272288",
"scheduler_job_id:r8/linux-internal",
"user_agent:luci-scheduler",
"buildset:commit/git/3c4406b84a9cc772a5e957905047c1a1c0a2d135",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/3c4406b84a9cc772a5e957905047c1a1c0a2d135",
"gitiles_ref:refs/heads/master"
]
}
[06:49:31.495] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"created_by": "project:r8",
"created_ts": "1587624570350775",
"id": "8882248694479208368",
"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\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@3c4406b84a9cc772a5e957905047c1a1c0a2d135\", \"title\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\", \"url\": \"https://r8.googlesource.com/r8/+/3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1587624571277238",
"tags": [
"build_address:luci.r8.ci/linux-internal/1031",
"builder:linux-internal",
"buildset:commit/git/3c4406b84a9cc772a5e957905047c1a1c0a2d135",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/3c4406b84a9cc772a5e957905047c1a1c0a2d135",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9047678756645272288",
"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/8882248694479208368/+/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": "1587624571277393",
"url": "https://ci.chromium.org/b/8882248694479208368",
"utcnow_ts": "1587624571480327"
}
}
[06:49:31.495] Task URL: https://ci.chromium.org/b/8882248694479208368
[06:49:31.495] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:2:0) after 1m0s
[06:50:01.633] Received PubSub notification, asking Buildbucket for the build status
[06:50:01.739] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:32.094] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:2:0)
[06:50:32.094] Timer tick, asking Buildbucket for the build status
[06:50:32.218] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:50:32.218] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:4:0) after 1m0s
[06:51:32.359] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:4:0)
[06:51:32.359] Timer tick, asking Buildbucket for the build status
[06:51:32.737] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:51:32.737] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:5:0) after 1m0s
[06:52:32.859] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:5:0)
[06:52:32.860] Timer tick, asking Buildbucket for the build status
[06:52:33.009] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:52:33.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:6:0) after 1m0s
[06:53:33.174] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:6:0)
[06:53:33.174] Timer tick, asking Buildbucket for the build status
[06:53:33.276] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:53:33.276] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:7:0) after 1m0s
[06:54:33.287] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:7:0)
[06:54:33.287] Timer tick, asking Buildbucket for the build status
[06:54:33.456] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:54:33.457] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:8:0) after 1m0s
[06:55:33.520] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:8:0)
[06:55:33.520] Timer tick, asking Buildbucket for the build status
[06:55:34.071] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:55:34.071] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:9:0) after 1m0s
[06:56:34.130] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:9:0)
[06:56:34.130] Timer tick, asking Buildbucket for the build status
[06:56:34.371] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:56:34.371] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:10:0) after 1m0s
[06:57:34.540] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:10:0)
[06:57:34.540] Timer tick, asking Buildbucket for the build status
[06:57:34.725] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:57:34.725] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:11:0) after 1m0s
[06:58:35.084] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:11:0)
[06:58:35.084] Timer tick, asking Buildbucket for the build status
[06:58:35.194] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:58:35.194] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:12:0) after 1m0s
[06:59:35.318] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:12:0)
[06:59:35.318] Timer tick, asking Buildbucket for the build status
[06:59:35.453] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:59:35.453] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:13:0) after 1m0s
[07:00:35.584] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:13:0)
[07:00:35.584] Timer tick, asking Buildbucket for the build status
[07:00:35.761] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:00:35.761] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:14:0) after 1m0s
[07:01:35.769] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:14:0)
[07:01:35.769] Timer tick, asking Buildbucket for the build status
[07:01:35.978] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:01:35.978] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:15:0) after 1m0s
[07:02:36.115] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:15:0)
[07:02:36.116] Timer tick, asking Buildbucket for the build status
[07:02:36.355] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:02:36.355] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:16:0) after 1m0s
[07:03:36.464] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:16:0)
[07:03:36.464] Timer tick, asking Buildbucket for the build status
[07:03:36.878] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:03:36.878] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:17:0) after 1m0s
[07:04:36.880] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:17:0)
[07:04:36.880] Timer tick, asking Buildbucket for the build status
[07:04:37.034] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:04:37.034] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:18:0) after 1m0s
[07:05:37.135] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:18:0)
[07:05:37.135] Timer tick, asking Buildbucket for the build status
[07:05:37.427] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:37.427] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:19:0) after 1m0s
[07:06:37.528] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:19:0)
[07:06:37.528] Timer tick, asking Buildbucket for the build status
[07:06:37.734] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:37.734] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:20:0) after 1m0s
[07:07:37.784] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:20:0)
[07:07:37.784] Timer tick, asking Buildbucket for the build status
[07:07:37.987] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:37.987] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:21:0) after 1m0s
[07:08:38.113] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:21:0)
[07:08:38.113] Timer tick, asking Buildbucket for the build status
[07:08:38.506] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:38.506] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:22:0) after 1m0s
[07:09:38.534] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:22:0)
[07:09:38.534] Timer tick, asking Buildbucket for the build status
[07:09:38.660] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:38.660] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:23:0) after 1m0s
[07:10:38.900] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:23:0)
[07:10:38.900] Timer tick, asking Buildbucket for the build status
[07:10:39.462] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:39.462] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:24:0) after 1m0s
[07:11:39.557] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:24:0)
[07:11:39.557] Timer tick, asking Buildbucket for the build status
[07:11:40.281] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:40.281] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:25:0) after 1m0s
[07:12:40.290] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:25:0)
[07:12:40.290] Timer tick, asking Buildbucket for the build status
[07:12:40.414] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:40.414] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:26:0) after 1m0s
[07:13:40.475] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:26:0)
[07:13:40.475] Timer tick, asking Buildbucket for the build status
[07:13:40.873] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:40.873] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:27:0) after 1m0s
[07:14:40.951] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:27:0)
[07:14:40.951] Timer tick, asking Buildbucket for the build status
[07:14:41.632] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:41.632] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:28:0) after 1m0s
[07:15:41.818] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:28:0)
[07:15:41.818] Timer tick, asking Buildbucket for the build status
[07:15:41.947] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:41.948] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:29:0) after 1m0s
[07:16:42.057] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:29:0)
[07:16:42.057] Timer tick, asking Buildbucket for the build status
[07:16:42.447] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:42.447] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:30:0) after 1m0s
[07:17:42.504] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:30:0)
[07:17:42.504] Timer tick, asking Buildbucket for the build status
[07:17:42.705] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:42.705] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:31:0) after 1m0s
[07:18:42.756] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:31:0)
[07:18:42.756] Timer tick, asking Buildbucket for the build status
[07:18:43.134] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:43.134] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:32:0) after 1m0s
[07:19:43.186] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:32:0)
[07:19:43.186] Timer tick, asking Buildbucket for the build status
[07:19:43.459] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:43.459] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:33:0) after 1m0s
[07:20:43.655] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:33:0)
[07:20:43.655] Timer tick, asking Buildbucket for the build status
[07:20:43.995] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:43.995] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:34:0) after 1m0s
[07:21:44.198] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:34:0)
[07:21:44.198] Timer tick, asking Buildbucket for the build status
[07:21:44.402] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:44.402] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:35:0) after 1m0s
[07:22:44.458] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:35:0)
[07:22:44.458] Timer tick, asking Buildbucket for the build status
[07:22:45.038] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:45.038] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:36:0) after 1m0s
[07:23:45.186] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:36:0)
[07:23:45.186] Timer tick, asking Buildbucket for the build status
[07:23:45.633] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:45.633] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:37:0) after 1m0s
[07:24:45.757] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:37:0)
[07:24:45.757] Timer tick, asking Buildbucket for the build status
[07:24:45.907] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:45.907] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:38:0) after 1m0s
[07:25:46.003] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:38:0)
[07:25:46.003] Timer tick, asking Buildbucket for the build status
[07:25:46.294] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:46.294] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:39:0) after 1m0s
[07:26:46.382] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:39:0)
[07:26:46.382] Timer tick, asking Buildbucket for the build status
[07:26:46.559] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:46.559] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:40:0) after 1m0s
[07:27:46.655] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:40:0)
[07:27:46.655] Timer tick, asking Buildbucket for the build status
[07:27:46.815] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:46.815] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:41:0) after 1m0s
[07:28:46.907] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:41:0)
[07:28:46.907] Timer tick, asking Buildbucket for the build status
[07:28:47.184] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:47.185] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:42:0) after 1m0s
[07:29:47.192] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:42:0)
[07:29:47.192] Timer tick, asking Buildbucket for the build status
[07:29:47.736] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:47.736] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:43:0) after 1m0s
[07:30:47.774] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:43:0)
[07:30:47.774] Timer tick, asking Buildbucket for the build status
[07:30:47.952] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:47.952] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:44:0) after 1m0s
[07:31:48.044] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:44:0)
[07:31:48.044] Timer tick, asking Buildbucket for the build status
[07:31:48.333] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:48.333] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:45:0) after 1m0s
[07:32:48.370] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:45:0)
[07:32:48.370] Timer tick, asking Buildbucket for the build status
[07:32:48.834] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:48.834] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:46:0) after 1m0s
[07:33:48.871] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:46:0)
[07:33:48.871] Timer tick, asking Buildbucket for the build status
[07:33:48.998] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:48.998] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:47:0) after 1m0s
[07:34:49.008] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:47:0)
[07:34:49.008] Timer tick, asking Buildbucket for the build status
[07:34:49.450] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:49.451] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:48:0) after 1m0s
[07:35:49.521] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:48:0)
[07:35:49.521] Timer tick, asking Buildbucket for the build status
[07:35:49.769] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:49.769] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:49:0) after 1m0s
[07:36:49.842] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:49:0)
[07:36:49.842] Timer tick, asking Buildbucket for the build status
[07:36:49.986] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:49.986] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:50:0) after 1m0s
[07:37:50.045] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:50:0)
[07:37:50.045] Timer tick, asking Buildbucket for the build status
[07:37:50.388] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:50.388] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:51:0) after 1m0s
[07:38:50.444] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:51:0)
[07:38:50.444] Timer tick, asking Buildbucket for the build status
[07:38:50.576] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:50.576] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:52:0) after 1m0s
[07:39:50.617] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:52:0)
[07:39:50.617] Timer tick, asking Buildbucket for the build status
[07:39:52.487] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:52.487] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:53:0) after 1m0s
[07:40:52.567] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:53:0)
[07:40:52.567] Timer tick, asking Buildbucket for the build status
[07:40:54.133] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:54.133] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:54:0) after 1m0s
[07:41:54.232] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:54:0)
[07:41:54.232] Timer tick, asking Buildbucket for the build status
[07:41:54.481] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:54.481] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:55:0) after 1m0s
[07:42:54.576] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:55:0)
[07:42:54.576] Timer tick, asking Buildbucket for the build status
[07:42:55.029] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:55.029] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:56:0) after 1m0s
[07:43:55.165] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:56:0)
[07:43:55.165] Timer tick, asking Buildbucket for the build status
[07:43:55.303] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:55.303] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:57:0) after 1m0s
[07:44:55.422] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:57:0)
[07:44:55.422] Timer tick, asking Buildbucket for the build status
[07:44:55.762] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:55.762] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:58:0) after 1m0s
[07:45:55.944] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:58:0)
[07:45:55.944] Timer tick, asking Buildbucket for the build status
[07:45:58.616] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:58.616] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:59:0) after 1m0s
[07:46:58.650] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:59:0)
[07:46:58.650] Timer tick, asking Buildbucket for the build status
[07:46:58.863] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:58.863] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:60:0) after 1m0s
[07:47:58.893] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:60:0)
[07:47:58.893] Timer tick, asking Buildbucket for the build status
[07:47:59.068] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:59.068] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:61:0) after 1m0s
[07:48:59.096] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:61:0)
[07:48:59.096] Timer tick, asking Buildbucket for the build status
[07:48:59.247] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:59.247] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:62:0) after 1m0s
[07:49:59.600] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:62:0)
[07:49:59.600] Timer tick, asking Buildbucket for the build status
[07:49:59.813] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:59.813] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:63:0) after 1m0s
[07:50:59.876] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:63:0)
[07:50:59.876] Timer tick, asking Buildbucket for the build status
[07:51:00.084] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:00.084] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:64:0) after 1m0s
[07:52:00.190] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:64:0)
[07:52:00.190] Timer tick, asking Buildbucket for the build status
[07:52:00.639] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:00.639] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:65:0) after 1m0s
[07:58:21.092] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:65:0)
[07:58:21.092] Timer tick, asking Buildbucket for the build status
[07:58:21.751] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:21.751] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:66:0) after 1m0s
[07:59:22.037] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:66:0)
[07:59:22.037] Timer tick, asking Buildbucket for the build status
[07:59:22.620] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:22.620] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:67:0) after 1m0s
[08:00:22.637] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:67:0)
[08:00:22.637] Timer tick, asking Buildbucket for the build status
[08:00:23.411] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:23.411] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:68:0) after 1m0s
[08:01:23.583] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:68:0)
[08:01:23.583] Timer tick, asking Buildbucket for the build status
[08:01:23.792] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:23.792] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:69:0) after 1m0s
[08:02:23.826] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:69:0)
[08:02:23.826] Timer tick, asking Buildbucket for the build status
[08:02:24.392] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:24.392] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:70:0) after 1m0s
[08:03:24.409] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:70:0)
[08:03:24.409] Timer tick, asking Buildbucket for the build status
[08:03:24.811] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:24.811] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:71:0) after 1m0s
[08:04:24.919] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:71:0)
[08:04:24.919] Timer tick, asking Buildbucket for the build status
[08:04:25.153] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:25.153] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:72:0) after 1m0s
[08:05:25.203] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:72:0)
[08:05:25.203] Timer tick, asking Buildbucket for the build status
[08:05:25.446] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:25.446] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:73:0) after 1m0s
[08:06:25.502] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:73:0)
[08:06:25.502] Timer tick, asking Buildbucket for the build status
[08:06:25.660] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:25.661] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:74:0) after 1m0s
[08:07:25.766] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:74:0)
[08:07:25.766] Timer tick, asking Buildbucket for the build status
[08:07:25.930] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:25.930] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:75:0) after 1m0s
[08:08:26.016] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:75:0)
[08:08:26.016] Timer tick, asking Buildbucket for the build status
[08:08:26.294] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:26.294] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:76:0) after 1m0s
[08:09:26.365] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:76:0)
[08:09:26.365] Timer tick, asking Buildbucket for the build status
[08:09:26.529] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:26.529] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:77:0) after 1m0s
[08:10:26.632] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:77:0)
[08:10:26.632] Timer tick, asking Buildbucket for the build status
[08:10:26.835] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:26.835] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:78:0) after 1m0s
[08:11:26.879] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:78:0)
[08:11:26.879] Timer tick, asking Buildbucket for the build status
[08:11:26.992] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:26.992] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:79:0) after 1m0s
[08:12:27.073] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:79:0)
[08:12:27.073] Timer tick, asking Buildbucket for the build status
[08:12:27.303] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:27.303] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:80:0) after 1m0s
[08:13:27.389] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:80:0)
[08:13:27.389] Timer tick, asking Buildbucket for the build status
[08:13:27.629] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:27.630] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:81:0) after 1m0s
[08:14:27.677] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:81:0)
[08:14:27.677] Timer tick, asking Buildbucket for the build status
[08:14:27.806] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:27.806] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:82:0) after 1m0s
[08:15:27.821] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:82:0)
[08:15:27.821] Timer tick, asking Buildbucket for the build status
[08:15:28.050] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:28.050] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:83:0) after 1m0s
[08:16:28.034] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:83:0)
[08:16:28.034] Timer tick, asking Buildbucket for the build status
[08:16:28.290] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:28.290] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:84:0) after 1m0s
[08:17:28.391] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:84:0)
[08:17:28.391] Timer tick, asking Buildbucket for the build status
[08:17:28.699] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:28.699] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:85:0) after 1m0s
[08:18:28.722] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:85:0)
[08:18:28.722] Timer tick, asking Buildbucket for the build status
[08:18:28.863] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:28.863] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:86:0) after 1m0s
[08:19:28.961] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:86:0)
[08:19:28.961] Timer tick, asking Buildbucket for the build status
[08:19:29.179] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:29.179] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:87:0) after 1m0s
[08:20:29.249] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:87:0)
[08:20:29.249] Timer tick, asking Buildbucket for the build status
[08:20:29.433] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:29.433] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:88:0) after 1m0s
[08:21:29.476] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:88:0)
[08:21:29.476] Timer tick, asking Buildbucket for the build status
[08:21:29.642] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:29.642] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:89:0) after 1m0s
[08:22:29.642] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:89:0)
[08:22:29.642] Timer tick, asking Buildbucket for the build status
[08:22:29.841] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:29.841] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:90:0) after 1m0s
[08:23:29.897] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:90:0)
[08:23:29.897] Timer tick, asking Buildbucket for the build status
[08:23:30.276] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:30.276] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:91:0) after 1m0s
[08:24:30.308] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:91:0)
[08:24:30.308] Timer tick, asking Buildbucket for the build status
[08:24:30.444] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:30.444] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:92:0) after 1m0s
[08:25:30.528] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:92:0)
[08:25:30.528] Timer tick, asking Buildbucket for the build status
[08:25:30.731] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:30.731] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:93:0) after 1m0s
[08:26:30.837] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:93:0)
[08:26:30.837] Timer tick, asking Buildbucket for the build status
[08:26:31.076] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:31.076] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:94:0) after 1m0s
[08:27:31.178] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:94:0)
[08:27:31.178] Timer tick, asking Buildbucket for the build status
[08:27:31.471] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:31.471] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:95:0) after 1m0s
[08:28:31.529] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:95:0)
[08:28:31.529] Timer tick, asking Buildbucket for the build status
[08:28:31.701] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:31.701] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:96:0) after 1m0s
[08:29:31.724] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:96:0)
[08:29:31.724] Timer tick, asking Buildbucket for the build status
[08:29:32.037] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:32.037] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:97:0) after 1m0s
[08:30:32.118] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:97:0)
[08:30:32.119] Timer tick, asking Buildbucket for the build status
[08:30:32.474] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:32.474] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:98:0) after 1m0s
[08:31:32.546] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:98:0)
[08:31:32.546] Timer tick, asking Buildbucket for the build status
[08:31:32.681] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:32.681] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:99:0) after 1m0s
[08:32:32.834] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:99:0)
[08:32:32.834] Timer tick, asking Buildbucket for the build status
[08:32:33.000] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:33.000] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:100:0) after 1m0s
[08:33:33.106] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:100:0)
[08:33:33.106] Timer tick, asking Buildbucket for the build status
[08:33:33.434] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:33.434] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:101:0) after 1m0s
[08:34:33.489] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:101:0)
[08:34:33.490] Timer tick, asking Buildbucket for the build status
[08:34:34.172] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:34.172] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:102:0) after 1m0s
[08:35:34.218] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:102:0)
[08:35:34.218] Timer tick, asking Buildbucket for the build status
[08:35:34.520] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:34.520] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:103:0) after 1m0s
[08:36:34.512] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:103:0)
[08:36:34.512] Timer tick, asking Buildbucket for the build status
[08:36:34.637] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:34.637] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:104:0) after 1m0s
[08:37:34.641] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:104:0)
[08:37:34.641] Timer tick, asking Buildbucket for the build status
[08:37:35.078] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:35.078] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:105:0) after 1m0s
[08:38:35.108] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:105:0)
[08:38:35.108] Timer tick, asking Buildbucket for the build status
[08:38:35.309] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:35.309] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:106:0) after 1m0s
[08:39:35.364] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:106:0)
[08:39:35.364] Timer tick, asking Buildbucket for the build status
[08:39:36.125] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:36.125] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:107:0) after 1m0s
[08:40:36.203] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:107:0)
[08:40:36.203] Timer tick, asking Buildbucket for the build status
[08:40:36.349] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:36.349] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:108:0) after 1m0s
[08:41:36.398] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:108:0)
[08:41:36.399] Timer tick, asking Buildbucket for the build status
[08:41:36.682] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:36.682] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:109:0) after 1m0s
[08:42:36.724] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:109:0)
[08:42:36.724] Timer tick, asking Buildbucket for the build status
[08:42:36.870] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:36.870] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:110:0) after 1m0s
[08:43:36.902] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:110:0)
[08:43:36.902] Timer tick, asking Buildbucket for the build status
[08:43:37.072] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:37.072] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:111:0) after 1m0s
[08:44:37.089] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:111:0)
[08:44:37.089] Timer tick, asking Buildbucket for the build status
[08:44:37.289] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:37.289] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:112:0) after 1m0s
[08:45:37.358] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:112:0)
[08:45:37.358] Timer tick, asking Buildbucket for the build status
[08:45:37.734] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:37.734] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:113:0) after 1m0s
[08:46:37.742] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:113:0)
[08:46:37.742] Timer tick, asking Buildbucket for the build status
[08:46:39.048] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:39.048] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:114:0) after 1m0s
[08:47:39.054] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:114:0)
[08:47:39.054] Timer tick, asking Buildbucket for the build status
[08:47:39.253] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:39.253] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:115:0) after 1m0s
[08:48:39.261] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:115:0)
[08:48:39.261] Timer tick, asking Buildbucket for the build status
[08:48:39.448] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:39.448] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:116:0) after 1m0s
[08:49:39.524] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:116:0)
[08:49:39.524] Timer tick, asking Buildbucket for the build status
[08:49:39.857] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:39.857] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:117:0) after 1m0s
[08:50:39.884] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:117:0)
[08:50:39.884] Timer tick, asking Buildbucket for the build status
[08:50:40.090] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:40.090] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:118:0) after 1m0s
[08:51:40.088] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:118:0)
[08:51:40.088] Timer tick, asking Buildbucket for the build status
[08:51:40.229] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:40.229] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:119:0) after 1m0s
[08:52:40.359] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:119:0)
[08:52:40.359] Timer tick, asking Buildbucket for the build status
[08:52:40.632] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:40.632] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:120:0) after 1m0s
[08:53:40.655] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:120:0)
[08:53:40.655] Timer tick, asking Buildbucket for the build status
[08:53:40.820] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:40.820] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:121:0) after 1m0s
[08:54:40.950] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:121:0)
[08:54:40.950] Timer tick, asking Buildbucket for the build status
[08:54:42.845] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:42.845] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:122:0) after 1m0s
[08:55:42.910] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:122:0)
[08:55:42.910] Timer tick, asking Buildbucket for the build status
[08:55:43.178] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:43.178] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:123:0) after 1m0s
[08:56:43.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:123:0)
[08:56:43.229] Timer tick, asking Buildbucket for the build status
[08:56:43.431] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:43.431] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:124:0) after 1m0s
[08:57:43.456] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:124:0)
[08:57:43.456] Timer tick, asking Buildbucket for the build status
[08:57:43.695] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:43.695] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:125:0) after 1m0s
[08:58:43.743] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:125:0)
[08:58:43.743] Timer tick, asking Buildbucket for the build status
[08:58:43.885] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:43.885] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:126:0) after 1m0s
[08:59:43.962] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:126:0)
[08:59:43.962] Timer tick, asking Buildbucket for the build status
[08:59:45.602] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:45.602] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:127:0) after 1m0s
[09:00:45.644] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:127:0)
[09:00:45.644] Timer tick, asking Buildbucket for the build status
[09:00:48.505] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:48.505] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:128:0) after 1m0s
[09:01:48.607] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:128:0)
[09:01:48.607] Timer tick, asking Buildbucket for the build status
[09:01:48.748] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:48.748] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:129:0) after 1m0s
[09:02:48.772] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:129:0)
[09:02:48.772] Timer tick, asking Buildbucket for the build status
[09:02:48.969] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:48.969] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:130:0) after 1m0s
[09:03:49.052] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:130:0)
[09:03:49.052] Timer tick, asking Buildbucket for the build status
[09:03:49.647] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:49.647] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:131:0) after 1m0s
[09:04:49.726] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:131:0)
[09:04:49.726] Timer tick, asking Buildbucket for the build status
[09:04:50.309] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:50.309] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:132:0) after 1m0s
[09:05:50.333] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:132:0)
[09:05:50.334] Timer tick, asking Buildbucket for the build status
[09:05:50.795] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:50.795] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:133:0) after 1m0s
[09:06:50.887] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:133:0)
[09:06:50.887] Timer tick, asking Buildbucket for the build status
[09:06:51.162] Build 8882248694479208368: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:51.162] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9047678756645272288:134:0) after 1m0s
[09:07:11.575] Received PubSub notification, asking Buildbucket for the build status
[09:07:11.723] Build 8882248694479208368: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:07:11.723] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"completed_ts": "1587632830967083",
"created_by": "project:r8",
"created_ts": "1587624570350775",
"failure_reason": "BUILD_FAILURE",
"id": "8882248694479208368",
"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\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@3c4406b84a9cc772a5e957905047c1a1c0a2d135\", \"title\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\", \"url\": \"https://r8.googlesource.com/r8/+/3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}}",
"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\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@3c4406b84a9cc772a5e957905047c1a1c0a2d135\", \"title\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\", \"url\": \"https://r8.googlesource.com/r8/+/3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}]}, \"bot_id\": \"luci-r8-ci-internal-0-v9wj\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-internal\", \"buildnumber\": 1031, \"internal\": \"True\", \"mastername\": \"internal.client.r8\", \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"3c4406b84a9cc772a5e957905047c1a1c0a2d135\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-r8-ci-internal-0-v9wj\"], \"image\": [\"chrome-xenial-20041200-f56df5dd7ad\"], \"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\": [\"5018-c7f912d\"], \"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": "1587624599303306",
"status": "COMPLETED",
"status_changed_ts": "1587624601400592",
"tags": [
"build_address:luci.r8.ci/linux-internal/1031",
"builder:linux-internal",
"buildset:commit/git/3c4406b84a9cc772a5e957905047c1a1c0a2d135",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/3c4406b84a9cc772a5e957905047c1a1c0a2d135",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9047678756645272288",
"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/8882248694479208368/+/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:4bbe9dfaf3f4f310",
"user_agent:luci-scheduler"
],
"updated_ts": "1587632831078801",
"url": "https://ci.chromium.org/b/8882248694479208368",
"utcnow_ts": "1587632831711862"
}
}
[09:07:11.723] Invocation finished in 2h17m42.822549083s with status FAILED