[08:39:30.820] New invocation is queued and will start shortly
[08:39:31.927] Starting the invocation (attempt 1)
[08:39:31.967] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:39:31.967] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:39:31.967] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9062801527437543440",
"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\":\"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@7b4d6231abc49d25a79d2681cb736da9b04b3d80\",\"title\":\"7b4d6231abc49d25a79d2681cb736da9b04b3d80\",\"url\":\"https://r8.googlesource.com/r8/+/7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-internal",
"scheduler_invocation_id:9062801527437543440",
"scheduler_job_id:r8/linux-internal",
"user_agent:luci-scheduler",
"buildset:commit/git/7b4d6231abc49d25a79d2681cb736da9b04b3d80",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/7b4d6231abc49d25a79d2681cb736da9b04b3d80",
"gitiles_ref:refs/heads/master"
]
}
[08:39:34.085] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"created_by": "project:r8",
"created_ts": "1573202372144659",
"id": "8897371465384818736",
"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\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@7b4d6231abc49d25a79d2681cb736da9b04b3d80\", \"title\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\", \"url\": \"https://r8.googlesource.com/r8/+/7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1573202373426494",
"tags": [
"build_address:luci.r8.ci/linux-internal/700",
"builder:linux-internal",
"buildset:commit/git/7b4d6231abc49d25a79d2681cb736da9b04b3d80",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/7b4d6231abc49d25a79d2681cb736da9b04b3d80",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062801527437543440",
"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/8897371465384818736/+/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": "1573202373428817",
"url": "https://ci.chromium.org/b/8897371465384818736",
"utcnow_ts": "1573202374067908"
}
}
[08:39:34.085] Task URL: https://ci.chromium.org/b/8897371465384818736
[08:39:34.085] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:2:0) after 1m0s
[08:40:17.356] Received PubSub notification, asking Buildbucket for the build status
[08:40:18.034] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:34.206] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:2:0)
[08:40:34.206] Timer tick, asking Buildbucket for the build status
[08:40:34.421] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:34.421] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:4:0) after 1m0s
[08:41:34.502] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:4:0)
[08:41:34.502] Timer tick, asking Buildbucket for the build status
[08:41:34.824] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:34.824] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:5:0) after 1m0s
[08:42:34.885] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:5:0)
[08:42:34.885] Timer tick, asking Buildbucket for the build status
[08:42:35.346] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:35.346] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:6:0) after 1m0s
[08:43:35.375] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:6:0)
[08:43:35.375] Timer tick, asking Buildbucket for the build status
[08:43:35.579] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:35.579] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:7:0) after 1m0s
[08:44:35.695] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:7:0)
[08:44:35.695] Timer tick, asking Buildbucket for the build status
[08:44:36.047] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:36.047] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:8:0) after 1m0s
[08:45:36.074] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:8:0)
[08:45:36.074] Timer tick, asking Buildbucket for the build status
[08:45:36.370] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:36.370] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:9:0) after 1m0s
[08:46:36.421] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:9:0)
[08:46:36.421] Timer tick, asking Buildbucket for the build status
[08:46:36.844] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:36.844] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:10:0) after 1m0s
[08:47:36.875] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:10:0)
[08:47:36.875] Timer tick, asking Buildbucket for the build status
[08:47:37.605] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:37.605] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:11:0) after 1m0s
[08:48:37.644] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:11:0)
[08:48:37.644] Timer tick, asking Buildbucket for the build status
[08:48:37.812] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:37.812] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:12:0) after 1m0s
[08:49:37.852] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:12:0)
[08:49:37.852] Timer tick, asking Buildbucket for the build status
[08:49:38.052] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:38.052] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:13:0) after 1m0s
[08:50:38.097] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:13:0)
[08:50:38.097] Timer tick, asking Buildbucket for the build status
[08:50:38.490] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:38.490] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:14:0) after 1m0s
[08:51:38.630] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:14:0)
[08:51:38.630] Timer tick, asking Buildbucket for the build status
[08:51:38.924] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:38.924] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:15:0) after 1m0s
[08:52:38.951] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:15:0)
[08:52:38.951] Timer tick, asking Buildbucket for the build status
[08:52:40.125] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:40.125] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:16:0) after 1m0s
[08:53:40.135] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:16:0)
[08:53:40.135] Timer tick, asking Buildbucket for the build status
[08:53:40.364] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:40.364] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:17:0) after 1m0s
[08:54:40.486] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:17:0)
[08:54:40.486] Timer tick, asking Buildbucket for the build status
[08:54:41.360] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:41.360] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:18:0) after 1m0s
[08:55:41.417] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:18:0)
[08:55:41.417] Timer tick, asking Buildbucket for the build status
[08:55:41.697] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:41.697] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:19:0) after 1m0s
[08:56:41.797] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:19:0)
[08:56:41.797] Timer tick, asking Buildbucket for the build status
[08:56:42.145] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:42.145] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:20:0) after 1m0s
[08:57:42.261] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:20:0)
[08:57:42.261] Timer tick, asking Buildbucket for the build status
[08:57:42.527] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:42.527] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:21:0) after 1m0s
[08:58:42.637] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:21:0)
[08:58:42.637] Timer tick, asking Buildbucket for the build status
[08:58:42.741] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:42.741] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:22:0) after 1m0s
[08:59:42.774] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:22:0)
[08:59:42.774] Timer tick, asking Buildbucket for the build status
[08:59:42.992] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:42.992] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:23:0) after 1m0s
[09:00:43.098] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:23:0)
[09:00:43.098] Timer tick, asking Buildbucket for the build status
[09:00:43.252] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:43.252] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:24:0) after 1m0s
[09:01:43.386] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:24:0)
[09:01:43.386] Timer tick, asking Buildbucket for the build status
[09:01:43.628] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:43.628] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:25:0) after 1m0s
[09:02:43.723] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:25:0)
[09:02:43.723] Timer tick, asking Buildbucket for the build status
[09:02:43.875] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:43.875] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:26:0) after 1m0s
[09:03:43.902] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:26:0)
[09:03:43.902] Timer tick, asking Buildbucket for the build status
[09:03:45.083] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:45.083] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:27:0) after 1m0s
[09:04:45.146] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:27:0)
[09:04:45.146] Timer tick, asking Buildbucket for the build status
[09:04:46.198] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:46.198] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:28:0) after 1m0s
[09:05:46.244] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:28:0)
[09:05:46.244] Timer tick, asking Buildbucket for the build status
[09:05:46.410] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:46.410] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:29:0) after 1m0s
[09:06:46.476] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:29:0)
[09:06:46.476] Timer tick, asking Buildbucket for the build status
[09:06:47.264] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:47.264] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:30:0) after 1m0s
[09:07:47.311] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:30:0)
[09:07:47.311] Timer tick, asking Buildbucket for the build status
[09:07:47.470] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:47.470] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:31:0) after 1m0s
[09:08:47.574] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:31:0)
[09:08:47.574] Timer tick, asking Buildbucket for the build status
[09:08:48.040] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:48.040] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:32:0) after 1m0s
[09:09:48.097] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:32:0)
[09:09:48.097] Timer tick, asking Buildbucket for the build status
[09:09:49.098] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:49.098] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:33:0) after 1m0s
[09:10:49.129] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:33:0)
[09:10:49.129] Timer tick, asking Buildbucket for the build status
[09:10:50.582] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:50.582] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:34:0) after 1m0s
[09:11:50.617] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:34:0)
[09:11:50.617] Timer tick, asking Buildbucket for the build status
[09:11:50.954] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:50.954] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:35:0) after 1m0s
[09:12:50.980] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:35:0)
[09:12:50.980] Timer tick, asking Buildbucket for the build status
[09:12:51.264] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:51.264] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:36:0) after 1m0s
[09:13:51.283] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:36:0)
[09:13:51.283] Timer tick, asking Buildbucket for the build status
[09:13:51.523] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:51.523] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:37:0) after 1m0s
[09:14:51.558] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:37:0)
[09:14:51.558] Timer tick, asking Buildbucket for the build status
[09:14:52.223] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:52.223] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:38:0) after 1m0s
[09:15:52.372] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:38:0)
[09:15:52.372] Timer tick, asking Buildbucket for the build status
[09:15:52.908] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:52.908] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:39:0) after 1m0s
[09:16:53.035] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:39:0)
[09:16:53.035] Timer tick, asking Buildbucket for the build status
[09:16:53.404] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:53.404] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:40:0) after 1m0s
[09:17:53.548] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:40:0)
[09:17:53.548] Timer tick, asking Buildbucket for the build status
[09:17:53.759] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:53.759] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:41:0) after 1m0s
[09:18:53.799] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:41:0)
[09:18:53.799] Timer tick, asking Buildbucket for the build status
[09:18:54.064] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:54.064] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:42:0) after 1m0s
[09:19:54.094] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:42:0)
[09:19:54.094] Timer tick, asking Buildbucket for the build status
[09:19:54.198] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:54.198] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:43:0) after 1m0s
[09:20:54.365] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:43:0)
[09:20:54.365] Timer tick, asking Buildbucket for the build status
[09:20:54.792] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:54.792] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:44:0) after 1m0s
[09:21:54.831] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:44:0)
[09:21:54.831] Timer tick, asking Buildbucket for the build status
[09:21:55.319] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:55.319] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:45:0) after 1m0s
[09:22:55.498] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:45:0)
[09:22:55.498] Timer tick, asking Buildbucket for the build status
[09:22:55.647] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:55.647] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:46:0) after 1m0s
[09:23:55.687] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:46:0)
[09:23:55.687] Timer tick, asking Buildbucket for the build status
[09:23:55.773] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:55.773] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:47:0) after 1m0s
[09:24:55.811] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:47:0)
[09:24:55.811] Timer tick, asking Buildbucket for the build status
[09:24:55.964] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:55.964] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:48:0) after 1m0s
[09:25:56.043] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:48:0)
[09:25:56.043] Timer tick, asking Buildbucket for the build status
[09:25:56.481] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:56.481] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:49:0) after 1m0s
[09:26:56.594] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:49:0)
[09:26:56.594] Timer tick, asking Buildbucket for the build status
[09:26:56.989] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:56.989] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:50:0) after 1m0s
[09:27:57.051] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:50:0)
[09:27:57.051] Timer tick, asking Buildbucket for the build status
[09:27:57.372] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:57.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:51:0) after 1m0s
[09:28:57.421] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:51:0)
[09:28:57.421] Timer tick, asking Buildbucket for the build status
[09:28:57.502] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:57.502] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:52:0) after 1m0s
[09:29:57.667] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:52:0)
[09:29:57.667] Timer tick, asking Buildbucket for the build status
[09:29:57.945] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:57.945] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:53:0) after 1m0s
[09:30:58.006] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:53:0)
[09:30:58.006] Timer tick, asking Buildbucket for the build status
[09:30:58.207] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:58.207] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:54:0) after 1m0s
[09:31:58.322] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:54:0)
[09:31:58.322] Timer tick, asking Buildbucket for the build status
[09:31:58.880] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:58.880] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:55:0) after 1m0s
[09:32:58.934] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:55:0)
[09:32:58.934] Timer tick, asking Buildbucket for the build status
[09:32:59.074] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:59.074] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:56:0) after 1m0s
[09:33:59.095] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:56:0)
[09:33:59.095] Timer tick, asking Buildbucket for the build status
[09:33:59.416] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:59.416] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:57:0) after 1m0s
[09:34:59.421] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:57:0)
[09:34:59.421] Timer tick, asking Buildbucket for the build status
[09:34:59.842] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:59.843] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:58:0) after 1m0s
[09:35:59.888] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:58:0)
[09:35:59.888] Timer tick, asking Buildbucket for the build status
[09:36:00.103] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:00.103] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:59:0) after 1m0s
[09:37:00.205] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:59:0)
[09:37:00.205] Timer tick, asking Buildbucket for the build status
[09:37:00.535] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:00.535] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:60:0) after 1m0s
[09:38:00.651] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:60:0)
[09:38:00.651] Timer tick, asking Buildbucket for the build status
[09:38:00.801] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:00.801] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:61:0) after 1m0s
[09:39:00.908] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:61:0)
[09:39:00.908] Timer tick, asking Buildbucket for the build status
[09:39:01.388] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:01.388] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:62:0) after 1m0s
[09:40:01.423] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:62:0)
[09:40:01.423] Timer tick, asking Buildbucket for the build status
[09:40:01.651] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:01.651] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:63:0) after 1m0s
[09:41:01.694] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:63:0)
[09:41:01.694] Timer tick, asking Buildbucket for the build status
[09:41:02.074] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:02.074] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:64:0) after 1m0s
[09:42:02.305] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:64:0)
[09:42:02.305] Timer tick, asking Buildbucket for the build status
[09:42:02.479] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:02.479] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:65:0) after 1m0s
[09:43:02.570] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:65:0)
[09:43:02.570] Timer tick, asking Buildbucket for the build status
[09:43:02.837] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:02.837] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:66:0) after 1m0s
[09:44:02.896] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:66:0)
[09:44:02.896] Timer tick, asking Buildbucket for the build status
[09:44:03.094] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:03.094] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:67:0) after 1m0s
[09:45:03.144] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:67:0)
[09:45:03.144] Timer tick, asking Buildbucket for the build status
[09:45:03.758] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:03.758] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:68:0) after 1m0s
[09:46:03.839] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:68:0)
[09:46:03.839] Timer tick, asking Buildbucket for the build status
[09:46:04.316] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:04.316] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:69:0) after 1m0s
[09:47:04.353] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:69:0)
[09:47:04.353] Timer tick, asking Buildbucket for the build status
[09:47:04.745] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:04.745] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:70:0) after 1m0s
[09:48:04.777] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:70:0)
[09:48:04.777] Timer tick, asking Buildbucket for the build status
[09:48:05.136] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:05.137] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:71:0) after 1m0s
[09:49:05.148] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:71:0)
[09:49:05.148] Timer tick, asking Buildbucket for the build status
[09:49:05.374] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:05.374] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:72:0) after 1m0s
[09:50:05.450] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:72:0)
[09:50:05.450] Timer tick, asking Buildbucket for the build status
[09:50:05.849] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:05.849] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:73:0) after 1m0s
[09:51:05.961] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:73:0)
[09:51:05.961] Timer tick, asking Buildbucket for the build status
[09:51:06.327] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:06.327] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:74:0) after 1m0s
[09:52:06.501] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:74:0)
[09:52:06.501] Timer tick, asking Buildbucket for the build status
[09:52:06.676] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:06.676] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:75:0) after 1m0s
[09:53:06.811] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:75:0)
[09:53:06.811] Timer tick, asking Buildbucket for the build status
[09:53:06.993] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:06.993] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:76:0) after 1m0s
[09:54:07.154] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:76:0)
[09:54:07.154] Timer tick, asking Buildbucket for the build status
[09:54:07.291] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:07.291] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:77:0) after 1m0s
[09:55:07.334] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:77:0)
[09:55:07.334] Timer tick, asking Buildbucket for the build status
[09:55:07.493] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:07.493] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:78:0) after 1m0s
[09:56:07.633] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:78:0)
[09:56:07.633] Timer tick, asking Buildbucket for the build status
[09:56:08.307] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:08.307] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:79:0) after 1m0s
[09:57:08.342] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:79:0)
[09:57:08.342] Timer tick, asking Buildbucket for the build status
[09:57:08.735] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:08.735] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:80:0) after 1m0s
[09:58:08.813] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:80:0)
[09:58:08.813] Timer tick, asking Buildbucket for the build status
[09:58:09.104] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:09.104] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:81:0) after 1m0s
[09:59:09.144] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:81:0)
[09:59:09.144] Timer tick, asking Buildbucket for the build status
[09:59:09.357] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:09.357] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:82:0) after 1m0s
[10:00:09.401] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:82:0)
[10:00:09.401] Timer tick, asking Buildbucket for the build status
[10:00:09.615] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:09.615] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:83:0) after 1m0s
[10:01:09.661] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:83:0)
[10:01:09.662] Timer tick, asking Buildbucket for the build status
[10:01:09.817] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:09.817] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:84:0) after 1m0s
[10:02:10.015] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:84:0)
[10:02:10.015] Timer tick, asking Buildbucket for the build status
[10:02:10.239] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:10.239] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:85:0) after 1m0s
[10:03:10.317] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:85:0)
[10:03:10.317] Timer tick, asking Buildbucket for the build status
[10:03:10.868] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:10.868] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:86:0) after 1m0s
[10:04:11.033] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:86:0)
[10:04:11.033] Timer tick, asking Buildbucket for the build status
[10:04:11.191] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:11.191] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:87:0) after 1m0s
[10:05:11.436] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:87:0)
[10:05:11.436] Timer tick, asking Buildbucket for the build status
[10:05:11.683] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:11.683] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:88:0) after 1m0s
[10:06:11.720] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:88:0)
[10:06:11.720] Timer tick, asking Buildbucket for the build status
[10:06:12.306] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:12.306] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:89:0) after 1m0s
[10:07:12.432] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:89:0)
[10:07:12.432] Timer tick, asking Buildbucket for the build status
[10:07:12.561] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:12.561] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:90:0) after 1m0s
[10:08:12.616] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:90:0)
[10:08:12.616] Timer tick, asking Buildbucket for the build status
[10:08:12.940] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:12.940] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:91:0) after 1m0s
[10:09:13.013] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:91:0)
[10:09:13.013] Timer tick, asking Buildbucket for the build status
[10:09:13.113] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:13.113] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:92:0) after 1m0s
[10:10:13.300] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:92:0)
[10:10:13.300] Timer tick, asking Buildbucket for the build status
[10:10:13.910] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:13.910] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:93:0) after 1m0s
[10:11:14.100] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:93:0)
[10:11:14.100] Timer tick, asking Buildbucket for the build status
[10:11:14.584] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:14.584] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:94:0) after 1m0s
[10:12:14.689] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:94:0)
[10:12:14.689] Timer tick, asking Buildbucket for the build status
[10:12:15.281] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:15.281] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:95:0) after 1m0s
[10:13:15.356] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:95:0)
[10:13:15.356] Timer tick, asking Buildbucket for the build status
[10:13:15.647] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:15.647] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:96:0) after 1m0s
[10:14:15.710] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:96:0)
[10:14:15.710] Timer tick, asking Buildbucket for the build status
[10:14:16.496] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:16.496] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:97:0) after 1m0s
[10:15:16.529] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:97:0)
[10:15:16.529] Timer tick, asking Buildbucket for the build status
[10:15:16.710] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:16.710] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:98:0) after 1m0s
[10:16:16.764] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:98:0)
[10:16:16.764] Timer tick, asking Buildbucket for the build status
[10:16:17.124] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:17.124] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:99:0) after 1m0s
[10:17:17.458] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:99:0)
[10:17:17.458] Timer tick, asking Buildbucket for the build status
[10:17:17.588] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:17.588] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:100:0) after 1m0s
[10:18:17.687] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:100:0)
[10:18:17.687] Timer tick, asking Buildbucket for the build status
[10:18:17.942] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:17.942] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:101:0) after 1m0s
[10:19:18.084] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:101:0)
[10:19:18.084] Timer tick, asking Buildbucket for the build status
[10:19:18.755] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:18.755] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:102:0) after 1m0s
[10:20:18.963] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:102:0)
[10:20:18.963] Timer tick, asking Buildbucket for the build status
[10:20:19.299] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:19.299] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:103:0) after 1m0s
[10:21:19.601] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:103:0)
[10:21:19.601] Timer tick, asking Buildbucket for the build status
[10:21:19.945] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:19.946] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:104:0) after 1m0s
[10:22:20.057] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:104:0)
[10:22:20.057] Timer tick, asking Buildbucket for the build status
[10:22:20.253] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:20.254] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:105:0) after 1m0s
[10:23:20.321] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:105:0)
[10:23:20.321] Timer tick, asking Buildbucket for the build status
[10:23:20.783] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:20.783] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:106:0) after 1m0s
[10:24:20.869] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:106:0)
[10:24:20.869] Timer tick, asking Buildbucket for the build status
[10:24:21.331] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:21.331] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:107:0) after 1m0s
[10:25:21.346] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:107:0)
[10:25:21.346] Timer tick, asking Buildbucket for the build status
[10:25:21.582] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:21.582] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:108:0) after 1m0s
[10:26:21.708] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:108:0)
[10:26:21.708] Timer tick, asking Buildbucket for the build status
[10:26:23.919] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:23.919] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:109:0) after 1m0s
[10:27:24.039] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:109:0)
[10:27:24.039] Timer tick, asking Buildbucket for the build status
[10:27:24.333] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:24.333] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:110:0) after 1m0s
[10:28:24.483] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:110:0)
[10:28:24.483] Timer tick, asking Buildbucket for the build status
[10:28:24.748] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:24.748] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:111:0) after 1m0s
[10:29:24.785] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:111:0)
[10:29:24.785] Timer tick, asking Buildbucket for the build status
[10:29:24.946] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:24.946] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:112:0) after 1m0s
[10:30:25.022] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:112:0)
[10:30:25.022] Timer tick, asking Buildbucket for the build status
[10:30:27.107] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:27.107] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:113:0) after 1m0s
[10:31:27.262] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:113:0)
[10:31:27.262] Timer tick, asking Buildbucket for the build status
[10:31:27.346] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:27.346] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:114:0) after 1m0s
[10:32:27.463] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:114:0)
[10:32:27.463] Timer tick, asking Buildbucket for the build status
[10:32:27.797] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:27.797] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:115:0) after 1m0s
[10:33:27.890] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:115:0)
[10:33:27.890] Timer tick, asking Buildbucket for the build status
[10:33:28.309] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:28.309] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:116:0) after 1m0s
[10:34:28.435] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:116:0)
[10:34:28.435] Timer tick, asking Buildbucket for the build status
[10:34:28.958] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:28.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:117:0) after 1m0s
[10:35:29.022] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:117:0)
[10:35:29.022] Timer tick, asking Buildbucket for the build status
[10:35:29.328] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:29.328] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:118:0) after 1m0s
[10:36:29.367] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:118:0)
[10:36:29.367] Timer tick, asking Buildbucket for the build status
[10:36:29.543] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:29.543] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:119:0) after 1m0s
[10:37:29.526] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:119:0)
[10:37:29.526] Timer tick, asking Buildbucket for the build status
[10:37:29.613] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:29.613] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:120:0) after 1m0s
[10:38:29.923] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:120:0)
[10:38:29.923] Timer tick, asking Buildbucket for the build status
[10:38:30.167] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:30.167] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:121:0) after 1m0s
[10:39:30.271] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:121:0)
[10:39:30.271] Timer tick, asking Buildbucket for the build status
[10:39:30.605] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:30.605] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:122:0) after 1m0s
[10:40:30.884] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:122:0)
[10:40:30.884] Timer tick, asking Buildbucket for the build status
[10:40:31.096] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:31.097] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:123:0) after 1m0s
[10:41:31.168] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:123:0)
[10:41:31.168] Timer tick, asking Buildbucket for the build status
[10:41:31.812] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:31.812] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:124:0) after 1m0s
[10:42:31.912] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:124:0)
[10:42:31.912] Timer tick, asking Buildbucket for the build status
[10:42:32.126] Build 8897371465384818736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:32.126] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9062801527437543440:125:0) after 1m0s
[10:43:22.776] Received PubSub notification, asking Buildbucket for the build status
[10:43:23.002] Build 8897371465384818736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:43:23.002] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"completed_ts": "1573209802190886",
"created_by": "project:r8",
"created_ts": "1573202372144659",
"failure_reason": "BUILD_FAILURE",
"id": "8897371465384818736",
"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\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@7b4d6231abc49d25a79d2681cb736da9b04b3d80\", \"title\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\", \"url\": \"https://r8.googlesource.com/r8/+/7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}}",
"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\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@7b4d6231abc49d25a79d2681cb736da9b04b3d80\", \"title\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\", \"url\": \"https://r8.googlesource.com/r8/+/7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}]}, \"bot_id\": \"luci-r8-ci-internal-0-6lob\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-internal\", \"buildnumber\": 700, \"internal\": \"True\", \"mastername\": \"internal.client.r8\", \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"7b4d6231abc49d25a79d2681cb736da9b04b3d80\"}, \"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-6lob\"], \"image\": [\"chrome-xenial-19102400-d35fedf5a0d\"], \"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\": [\"4621-f8a5b1c\"], \"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": "1573202415778666",
"status": "COMPLETED",
"status_changed_ts": "1573202417037774",
"tags": [
"build_address:luci.r8.ci/linux-internal/700",
"builder:linux-internal",
"buildset:commit/git/7b4d6231abc49d25a79d2681cb736da9b04b3d80",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/7b4d6231abc49d25a79d2681cb736da9b04b3d80",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9062801527437543440",
"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/8897371465384818736/+/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:4862fd0096510b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1573209802255939",
"url": "https://ci.chromium.org/b/8897371465384818736",
"utcnow_ts": "1573209802992030"
}
}
[10:43:23.002] Invocation finished in 2h3m52.199993853s with status FAILED