[09:20:53.962] New invocation is queued and will start shortly
[09:20:55.168] Starting the invocation (attempt 1)
[09:20:55.216] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:20:55.216] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:20:55.216] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9034804461056647152",
"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\":\"4f809c287c07e3a96b0529508d180bb1c56786ff\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@4f809c287c07e3a96b0529508d180bb1c56786ff\",\"title\":\"4f809c287c07e3a96b0529508d180bb1c56786ff\",\"url\":\"https://r8.googlesource.com/r8/+/4f809c287c07e3a96b0529508d180bb1c56786ff\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"4f809c287c07e3a96b0529508d180bb1c56786ff\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-internal",
"scheduler_invocation_id:9034804461056647152",
"scheduler_job_id:r8/linux-internal",
"user_agent:luci-scheduler",
"buildset:commit/git/4f809c287c07e3a96b0529508d180bb1c56786ff",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/4f809c287c07e3a96b0529508d180bb1c56786ff",
"gitiles_ref:refs/heads/master"
]
}
[09:20:56.456] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:r8",
"created_ts": "1599902455323540",
"id": "8869374398965364736",
"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\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@4f809c287c07e3a96b0529508d180bb1c56786ff\", \"title\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\", \"url\": \"https://r8.googlesource.com/r8/+/4f809c287c07e3a96b0529508d180bb1c56786ff\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1599902456303746",
"tags": [
"build_address:luci.r8.ci/linux-internal/1304",
"builder:linux-internal",
"buildset:commit/git/4f809c287c07e3a96b0529508d180bb1c56786ff",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/4f809c287c07e3a96b0529508d180bb1c56786ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034804461056647152",
"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/8869374398965364736/+/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": "1599902456303854",
"url": "https://ci.chromium.org/b/8869374398965364736",
"utcnow_ts": "1599902456442812"
}
}
[09:20:56.456] Task URL: https://ci.chromium.org/b/8869374398965364736
[09:20:56.456] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:2:0) after 1m0s
[09:21:37.615] Received PubSub notification, asking Buildbucket for the build status
[09:21:37.830] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:56.488] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:2:0)
[09:21:56.488] Timer tick, asking Buildbucket for the build status
[09:21:56.571] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:56.571] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:4:0) after 1m0s
[09:22:56.645] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:4:0)
[09:22:56.645] Timer tick, asking Buildbucket for the build status
[09:22:56.847] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:56.847] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:5:0) after 1m0s
[09:23:57.002] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:5:0)
[09:23:57.002] Timer tick, asking Buildbucket for the build status
[09:23:57.076] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:57.076] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:6:0) after 1m0s
[09:24:59.697] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:6:0)
[09:24:59.697] Timer tick, asking Buildbucket for the build status
[09:24:59.758] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:59.758] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:7:0) after 1m0s
[09:25:59.844] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:7:0)
[09:25:59.844] Timer tick, asking Buildbucket for the build status
[09:25:59.994] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:59.994] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:8:0) after 1m0s
[09:27:00.089] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:8:0)
[09:27:00.089] Timer tick, asking Buildbucket for the build status
[09:27:00.175] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:00.175] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:9:0) after 1m0s
[09:28:00.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:9:0)
[09:28:00.229] Timer tick, asking Buildbucket for the build status
[09:28:00.387] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:00.387] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:10:0) after 1m0s
[09:29:00.438] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:10:0)
[09:29:00.438] Timer tick, asking Buildbucket for the build status
[09:29:00.562] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:00.562] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:11:0) after 1m0s
[09:30:00.592] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:11:0)
[09:30:00.592] Timer tick, asking Buildbucket for the build status
[09:30:00.911] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:00.911] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:12:0) after 1m0s
[09:31:00.930] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:12:0)
[09:31:00.930] Timer tick, asking Buildbucket for the build status
[09:31:01.185] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:01.185] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:13:0) after 1m0s
[09:32:01.510] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:13:0)
[09:32:01.510] Timer tick, asking Buildbucket for the build status
[09:32:01.613] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:01.613] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:14:0) after 1m0s
[09:33:01.685] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:14:0)
[09:33:01.685] Timer tick, asking Buildbucket for the build status
[09:33:01.923] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:01.923] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:15:0) after 1m0s
[09:34:01.923] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:15:0)
[09:34:01.923] Timer tick, asking Buildbucket for the build status
[09:34:02.417] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:02.417] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:16:0) after 1m0s
[09:35:02.530] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:16:0)
[09:35:02.530] Timer tick, asking Buildbucket for the build status
[09:35:02.595] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:02.595] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:17:0) after 1m0s
[09:36:02.641] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:17:0)
[09:36:02.641] Timer tick, asking Buildbucket for the build status
[09:36:03.095] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:03.095] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:18:0) after 1m0s
[09:37:03.129] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:18:0)
[09:37:03.129] Timer tick, asking Buildbucket for the build status
[09:37:03.354] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:03.354] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:19:0) after 1m0s
[09:38:03.489] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:19:0)
[09:38:03.489] Timer tick, asking Buildbucket for the build status
[09:38:03.570] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:03.570] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:20:0) after 1m0s
[09:39:03.700] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:20:0)
[09:39:03.700] Timer tick, asking Buildbucket for the build status
[09:39:03.764] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:03.764] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:21:0) after 1m0s
[09:40:03.907] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:21:0)
[09:40:03.907] Timer tick, asking Buildbucket for the build status
[09:40:03.968] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:03.968] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:22:0) after 1m0s
[09:41:03.978] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:22:0)
[09:41:03.978] Timer tick, asking Buildbucket for the build status
[09:41:04.086] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:04.086] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:23:0) after 1m0s
[09:42:04.151] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:23:0)
[09:42:04.151] Timer tick, asking Buildbucket for the build status
[09:42:04.231] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:04.231] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:24:0) after 1m0s
[09:43:04.330] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:24:0)
[09:43:04.330] Timer tick, asking Buildbucket for the build status
[09:43:04.403] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:04.403] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:25:0) after 1m0s
[09:44:04.524] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:25:0)
[09:44:04.524] Timer tick, asking Buildbucket for the build status
[09:44:04.590] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:04.591] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:26:0) after 1m0s
[09:45:04.626] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:26:0)
[09:45:04.626] Timer tick, asking Buildbucket for the build status
[09:45:04.889] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:04.889] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:27:0) after 1m0s
[09:46:04.978] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:27:0)
[09:46:04.978] Timer tick, asking Buildbucket for the build status
[09:46:05.161] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:05.161] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:28:0) after 1m0s
[09:47:05.295] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:28:0)
[09:47:05.295] Timer tick, asking Buildbucket for the build status
[09:47:05.366] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:05.366] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:29:0) after 1m0s
[09:48:05.393] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:29:0)
[09:48:05.393] Timer tick, asking Buildbucket for the build status
[09:48:05.521] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:05.521] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:30:0) after 1m0s
[09:49:05.594] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:30:0)
[09:49:05.594] Timer tick, asking Buildbucket for the build status
[09:49:05.685] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:05.685] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:31:0) after 1m0s
[09:50:05.889] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:31:0)
[09:50:05.889] Timer tick, asking Buildbucket for the build status
[09:50:06.000] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:06.000] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:32:0) after 1m0s
[09:51:06.462] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:32:0)
[09:51:06.462] Timer tick, asking Buildbucket for the build status
[09:51:06.570] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:06.570] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:33:0) after 1m0s
[09:52:06.730] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:33:0)
[09:52:06.730] Timer tick, asking Buildbucket for the build status
[09:52:06.802] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:06.803] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:34:0) after 1m0s
[09:53:06.852] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:34:0)
[09:53:06.852] Timer tick, asking Buildbucket for the build status
[09:53:06.961] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:06.961] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:35:0) after 1m0s
[09:54:07.106] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:35:0)
[09:54:07.106] Timer tick, asking Buildbucket for the build status
[09:54:07.508] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:07.508] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:36:0) after 1m0s
[09:55:07.528] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:36:0)
[09:55:07.528] Timer tick, asking Buildbucket for the build status
[09:55:07.812] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:07.812] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:37:0) after 1m0s
[09:56:07.837] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:37:0)
[09:56:07.837] Timer tick, asking Buildbucket for the build status
[09:56:08.019] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:08.019] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:38:0) after 1m0s
[09:57:08.190] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:38:0)
[09:57:08.190] Timer tick, asking Buildbucket for the build status
[09:57:08.254] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:08.254] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:39:0) after 1m0s
[09:58:08.236] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:39:0)
[09:58:08.236] Timer tick, asking Buildbucket for the build status
[09:58:08.326] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:08.326] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:40:0) after 1m0s
[09:59:08.312] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:40:0)
[09:59:08.312] Timer tick, asking Buildbucket for the build status
[09:59:08.491] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:08.491] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:41:0) after 1m0s
[10:00:08.502] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:41:0)
[10:00:08.502] Timer tick, asking Buildbucket for the build status
[10:00:08.713] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:08.713] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:42:0) after 1m0s
[10:01:10.129] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:42:0)
[10:01:10.129] Timer tick, asking Buildbucket for the build status
[10:01:10.256] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:10.256] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:43:0) after 1m0s
[10:02:10.360] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:43:0)
[10:02:10.361] Timer tick, asking Buildbucket for the build status
[10:02:10.561] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:10.561] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:44:0) after 1m0s
[10:03:10.548] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:44:0)
[10:03:10.548] Timer tick, asking Buildbucket for the build status
[10:03:10.621] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:10.621] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:45:0) after 1m0s
[10:04:10.764] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:45:0)
[10:04:10.764] Timer tick, asking Buildbucket for the build status
[10:04:10.977] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:10.977] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:46:0) after 1m0s
[10:05:11.049] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:46:0)
[10:05:11.049] Timer tick, asking Buildbucket for the build status
[10:05:11.173] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:11.173] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:47:0) after 1m0s
[10:06:11.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:47:0)
[10:06:11.229] Timer tick, asking Buildbucket for the build status
[10:06:11.409] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:11.409] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:48:0) after 1m0s
[10:07:11.451] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:48:0)
[10:07:11.451] Timer tick, asking Buildbucket for the build status
[10:07:11.785] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:11.785] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:49:0) after 1m0s
[10:08:11.843] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:49:0)
[10:08:11.843] Timer tick, asking Buildbucket for the build status
[10:08:11.911] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:11.911] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:50:0) after 1m0s
[10:09:11.935] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:50:0)
[10:09:11.935] Timer tick, asking Buildbucket for the build status
[10:09:12.133] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:12.133] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:51:0) after 1m0s
[10:10:12.158] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:51:0)
[10:10:12.158] Timer tick, asking Buildbucket for the build status
[10:10:12.296] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:12.296] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:52:0) after 1m0s
[10:11:12.318] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:52:0)
[10:11:12.318] Timer tick, asking Buildbucket for the build status
[10:11:12.408] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:12.408] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:53:0) after 1m0s
[10:12:12.537] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:53:0)
[10:12:12.537] Timer tick, asking Buildbucket for the build status
[10:12:12.638] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:12.639] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:54:0) after 1m0s
[10:13:12.775] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:54:0)
[10:13:12.775] Timer tick, asking Buildbucket for the build status
[10:13:12.837] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:12.837] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:55:0) after 1m0s
[10:14:12.920] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:55:0)
[10:14:12.920] Timer tick, asking Buildbucket for the build status
[10:14:13.000] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:13.000] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:56:0) after 1m0s
[10:15:13.125] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:56:0)
[10:15:13.125] Timer tick, asking Buildbucket for the build status
[10:15:13.306] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:13.306] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:57:0) after 1m0s
[10:16:13.498] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:57:0)
[10:16:13.498] Timer tick, asking Buildbucket for the build status
[10:16:13.795] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:13.795] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:58:0) after 1m0s
[10:17:13.907] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:58:0)
[10:17:13.907] Timer tick, asking Buildbucket for the build status
[10:17:14.269] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:14.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:59:0) after 1m0s
[10:18:14.431] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:59:0)
[10:18:14.431] Timer tick, asking Buildbucket for the build status
[10:18:14.495] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:14.495] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:60:0) after 1m0s
[10:19:14.523] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:60:0)
[10:19:14.523] Timer tick, asking Buildbucket for the build status
[10:19:14.577] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:14.577] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:61:0) after 1m0s
[10:20:14.731] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:61:0)
[10:20:14.732] Timer tick, asking Buildbucket for the build status
[10:20:15.113] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:15.113] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:62:0) after 1m0s
[10:21:15.285] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:62:0)
[10:21:15.285] Timer tick, asking Buildbucket for the build status
[10:21:15.406] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:15.406] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:63:0) after 1m0s
[10:22:15.468] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:63:0)
[10:22:15.468] Timer tick, asking Buildbucket for the build status
[10:22:15.590] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:15.590] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:64:0) after 1m0s
[10:23:15.755] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:64:0)
[10:23:15.755] Timer tick, asking Buildbucket for the build status
[10:23:15.827] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:15.827] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:65:0) after 1m0s
[10:24:15.850] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:65:0)
[10:24:15.850] Timer tick, asking Buildbucket for the build status
[10:24:15.922] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:15.922] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:66:0) after 1m0s
[10:25:16.059] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:66:0)
[10:25:16.059] Timer tick, asking Buildbucket for the build status
[10:25:16.135] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:16.135] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:67:0) after 1m0s
[10:26:16.197] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:67:0)
[10:26:16.197] Timer tick, asking Buildbucket for the build status
[10:26:16.626] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:16.626] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:68:0) after 1m0s
[10:27:16.706] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:68:0)
[10:27:16.706] Timer tick, asking Buildbucket for the build status
[10:27:16.796] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:16.796] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:69:0) after 1m0s
[10:28:16.856] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:69:0)
[10:28:16.856] Timer tick, asking Buildbucket for the build status
[10:28:17.180] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:17.180] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:70:0) after 1m0s
[10:29:17.255] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:70:0)
[10:29:17.255] Timer tick, asking Buildbucket for the build status
[10:29:17.599] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:17.599] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:71:0) after 1m0s
[10:30:17.647] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:71:0)
[10:30:17.647] Timer tick, asking Buildbucket for the build status
[10:30:17.710] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:17.710] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:72:0) after 1m0s
[10:31:17.807] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:72:0)
[10:31:17.807] Timer tick, asking Buildbucket for the build status
[10:31:17.925] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:17.925] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:73:0) after 1m0s
[10:32:18.049] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:73:0)
[10:32:18.049] Timer tick, asking Buildbucket for the build status
[10:32:18.402] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:18.402] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:74:0) after 1m0s
[10:33:18.456] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:74:0)
[10:33:18.456] Timer tick, asking Buildbucket for the build status
[10:33:18.720] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:18.720] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:75:0) after 1m0s
[10:34:18.797] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:75:0)
[10:34:18.797] Timer tick, asking Buildbucket for the build status
[10:34:18.873] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:18.873] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:76:0) after 1m0s
[10:35:19.074] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:76:0)
[10:35:19.074] Timer tick, asking Buildbucket for the build status
[10:35:19.313] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:19.314] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:77:0) after 1m0s
[10:36:19.368] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:77:0)
[10:36:19.368] Timer tick, asking Buildbucket for the build status
[10:36:19.504] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:19.504] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:78:0) after 1m0s
[10:37:19.647] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:78:0)
[10:37:19.647] Timer tick, asking Buildbucket for the build status
[10:37:19.933] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:19.933] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:79:0) after 1m0s
[10:38:19.981] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:79:0)
[10:38:19.981] Timer tick, asking Buildbucket for the build status
[10:38:20.110] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:20.110] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:80:0) after 1m0s
[10:39:20.310] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:80:0)
[10:39:20.310] Timer tick, asking Buildbucket for the build status
[10:39:20.372] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:20.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:81:0) after 1m0s
[10:40:20.497] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:81:0)
[10:40:20.497] Timer tick, asking Buildbucket for the build status
[10:40:20.646] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:20.646] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:82:0) after 1m0s
[10:41:20.812] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:82:0)
[10:41:20.812] Timer tick, asking Buildbucket for the build status
[10:41:21.330] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:21.330] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:83:0) after 1m0s
[10:42:21.534] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:83:0)
[10:42:21.534] Timer tick, asking Buildbucket for the build status
[10:42:21.613] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:21.613] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:84:0) after 1m0s
[10:43:21.683] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:84:0)
[10:43:21.683] Timer tick, asking Buildbucket for the build status
[10:43:21.820] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:21.820] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:85:0) after 1m0s
[10:44:21.939] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:85:0)
[10:44:21.939] Timer tick, asking Buildbucket for the build status
[10:44:22.003] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:22.003] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:86:0) after 1m0s
[10:45:22.063] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:86:0)
[10:45:22.063] Timer tick, asking Buildbucket for the build status
[10:45:22.139] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:22.139] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:87:0) after 1m0s
[10:46:22.170] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:87:0)
[10:46:22.170] Timer tick, asking Buildbucket for the build status
[10:46:22.403] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:22.403] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:88:0) after 1m0s
[10:47:22.516] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:88:0)
[10:47:22.516] Timer tick, asking Buildbucket for the build status
[10:47:22.601] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:22.601] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:89:0) after 1m0s
[10:48:22.722] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:89:0)
[10:48:22.722] Timer tick, asking Buildbucket for the build status
[10:48:22.994] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:22.994] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:90:0) after 1m0s
[10:49:23.082] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:90:0)
[10:49:23.082] Timer tick, asking Buildbucket for the build status
[10:49:23.144] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:23.144] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:91:0) after 1m0s
[10:50:23.315] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:91:0)
[10:50:23.315] Timer tick, asking Buildbucket for the build status
[10:50:23.805] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:23.805] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:92:0) after 1m0s
[10:51:23.862] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:92:0)
[10:51:23.862] Timer tick, asking Buildbucket for the build status
[10:51:24.115] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:24.115] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:93:0) after 1m0s
[10:52:24.249] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:93:0)
[10:52:24.249] Timer tick, asking Buildbucket for the build status
[10:52:24.323] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:24.323] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:94:0) after 1m0s
[10:53:24.576] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:94:0)
[10:53:24.576] Timer tick, asking Buildbucket for the build status
[10:53:24.916] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:24.916] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:95:0) after 1m0s
[10:54:25.052] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:95:0)
[10:54:25.052] Timer tick, asking Buildbucket for the build status
[10:54:25.126] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:25.126] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:96:0) after 1m0s
[10:55:25.306] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:96:0)
[10:55:25.306] Timer tick, asking Buildbucket for the build status
[10:55:25.498] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:25.498] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:97:0) after 1m0s
[10:56:25.568] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:97:0)
[10:56:25.568] Timer tick, asking Buildbucket for the build status
[10:56:25.727] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:25.727] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:98:0) after 1m0s
[10:57:25.757] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:98:0)
[10:57:25.757] Timer tick, asking Buildbucket for the build status
[10:57:25.980] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:25.980] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:99:0) after 1m0s
[10:58:26.026] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:99:0)
[10:58:26.026] Timer tick, asking Buildbucket for the build status
[10:58:26.088] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:26.089] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:100:0) after 1m0s
[10:59:26.223] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:100:0)
[10:59:26.223] Timer tick, asking Buildbucket for the build status
[10:59:26.316] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:26.316] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:101:0) after 1m0s
[11:00:26.376] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:101:0)
[11:00:26.376] Timer tick, asking Buildbucket for the build status
[11:00:26.455] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:26.455] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:102:0) after 1m0s
[11:01:26.603] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:102:0)
[11:01:26.603] Timer tick, asking Buildbucket for the build status
[11:01:26.835] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:26.835] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:103:0) after 1m0s
[11:02:26.881] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:103:0)
[11:02:26.881] Timer tick, asking Buildbucket for the build status
[11:02:26.968] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:26.968] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:104:0) after 1m0s
[11:03:27.028] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:104:0)
[11:03:27.028] Timer tick, asking Buildbucket for the build status
[11:03:27.398] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:27.398] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:105:0) after 1m0s
[11:04:27.450] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:105:0)
[11:04:27.450] Timer tick, asking Buildbucket for the build status
[11:04:27.508] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:27.508] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:106:0) after 1m0s
[11:05:27.604] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:106:0)
[11:05:27.604] Timer tick, asking Buildbucket for the build status
[11:05:27.880] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:27.880] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:107:0) after 1m0s
[11:06:27.959] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:107:0)
[11:06:27.960] Timer tick, asking Buildbucket for the build status
[11:06:28.129] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:28.129] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:108:0) after 1m0s
[11:07:28.133] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:108:0)
[11:07:28.133] Timer tick, asking Buildbucket for the build status
[11:07:28.342] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:28.343] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:109:0) after 1m0s
[11:08:28.339] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:109:0)
[11:08:28.339] Timer tick, asking Buildbucket for the build status
[11:08:28.402] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:28.402] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:110:0) after 1m0s
[11:09:28.447] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:110:0)
[11:09:28.447] Timer tick, asking Buildbucket for the build status
[11:09:28.988] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:28.988] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:111:0) after 1m0s
[11:10:29.017] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:111:0)
[11:10:29.017] Timer tick, asking Buildbucket for the build status
[11:10:29.103] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:29.103] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:112:0) after 1m0s
[11:11:29.136] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:112:0)
[11:11:29.136] Timer tick, asking Buildbucket for the build status
[11:11:29.405] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:29.405] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:113:0) after 1m0s
[11:12:29.429] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:113:0)
[11:12:29.429] Timer tick, asking Buildbucket for the build status
[11:12:29.493] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:29.493] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:114:0) after 1m0s
[11:13:29.571] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:114:0)
[11:13:29.571] Timer tick, asking Buildbucket for the build status
[11:13:29.637] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:29.637] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:115:0) after 1m0s
[11:14:29.661] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:115:0)
[11:14:29.661] Timer tick, asking Buildbucket for the build status
[11:14:29.899] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:29.899] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:116:0) after 1m0s
[11:15:29.925] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:116:0)
[11:15:29.925] Timer tick, asking Buildbucket for the build status
[11:15:30.205] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:30.205] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:117:0) after 1m0s
[11:16:30.229] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:117:0)
[11:16:30.229] Timer tick, asking Buildbucket for the build status
[11:16:30.478] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:30.478] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:118:0) after 1m0s
[11:17:30.548] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:118:0)
[11:17:30.548] Timer tick, asking Buildbucket for the build status
[11:17:30.683] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:30.683] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:119:0) after 1m0s
[11:18:30.737] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:119:0)
[11:18:30.737] Timer tick, asking Buildbucket for the build status
[11:18:31.083] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:31.083] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:120:0) after 1m0s
[11:19:31.135] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:120:0)
[11:19:31.135] Timer tick, asking Buildbucket for the build status
[11:19:31.204] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:31.204] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:121:0) after 1m0s
[11:20:31.239] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:121:0)
[11:20:31.239] Timer tick, asking Buildbucket for the build status
[11:20:31.319] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:31.320] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:122:0) after 1m0s
[11:21:31.416] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:122:0)
[11:21:31.416] Timer tick, asking Buildbucket for the build status
[11:21:31.774] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:31.774] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:123:0) after 1m0s
[11:22:31.814] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:123:0)
[11:22:31.814] Timer tick, asking Buildbucket for the build status
[11:22:31.898] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:31.898] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:124:0) after 1m0s
[11:23:32.010] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:124:0)
[11:23:32.010] Timer tick, asking Buildbucket for the build status
[11:23:32.085] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:32.085] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:125:0) after 1m0s
[11:24:32.197] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:125:0)
[11:24:32.197] Timer tick, asking Buildbucket for the build status
[11:24:32.249] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:32.249] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:126:0) after 1m0s
[11:25:32.375] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:126:0)
[11:25:32.375] Timer tick, asking Buildbucket for the build status
[11:25:32.448] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:32.448] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:127:0) after 1m0s
[11:26:32.612] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:127:0)
[11:26:32.612] Timer tick, asking Buildbucket for the build status
[11:26:32.738] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:32.738] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:128:0) after 1m0s
[11:27:32.757] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:128:0)
[11:27:32.757] Timer tick, asking Buildbucket for the build status
[11:27:32.878] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:32.878] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:129:0) after 1m0s
[11:28:32.876] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:129:0)
[11:28:32.876] Timer tick, asking Buildbucket for the build status
[11:28:33.043] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:33.043] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:130:0) after 1m0s
[11:29:33.111] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:130:0)
[11:29:33.111] Timer tick, asking Buildbucket for the build status
[11:29:33.169] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:33.169] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:131:0) after 1m0s
[11:30:33.228] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:131:0)
[11:30:33.228] Timer tick, asking Buildbucket for the build status
[11:30:33.303] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:33.303] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:132:0) after 1m0s
[11:31:33.343] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:132:0)
[11:31:33.343] Timer tick, asking Buildbucket for the build status
[11:31:34.004] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:34.004] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:133:0) after 1m0s
[11:32:34.118] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:133:0)
[11:32:34.118] Timer tick, asking Buildbucket for the build status
[11:32:40.958] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:40.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:134:0) after 1m0s
[11:33:41.027] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:134:0)
[11:33:41.027] Timer tick, asking Buildbucket for the build status
[11:33:41.109] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:41.109] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:135:0) after 1m0s
[11:34:41.194] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:135:0)
[11:34:41.194] Timer tick, asking Buildbucket for the build status
[11:34:41.275] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:41.275] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:136:0) after 1m0s
[11:35:41.531] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:136:0)
[11:35:41.531] Timer tick, asking Buildbucket for the build status
[11:35:41.613] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:41.613] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:137:0) after 1m0s
[11:36:41.747] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:137:0)
[11:36:41.747] Timer tick, asking Buildbucket for the build status
[11:36:41.868] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:41.868] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:138:0) after 1m0s
[11:37:42.075] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:138:0)
[11:37:42.075] Timer tick, asking Buildbucket for the build status
[11:37:42.138] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:42.138] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:139:0) after 1m0s
[11:38:42.223] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:139:0)
[11:38:42.223] Timer tick, asking Buildbucket for the build status
[11:38:42.419] Build 8869374398965364736: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:42.419] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9034804461056647152:140:0) after 1m0s
[11:38:43.928] Received PubSub notification, asking Buildbucket for the build status
[11:38:44.000] Build 8869374398965364736: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[11:38:44.000] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1599910723629989",
"created_by": "project:r8",
"created_ts": "1599902455323540",
"failure_reason": "BUILD_FAILURE",
"id": "8869374398965364736",
"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\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@4f809c287c07e3a96b0529508d180bb1c56786ff\", \"title\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\", \"url\": \"https://r8.googlesource.com/r8/+/4f809c287c07e3a96b0529508d180bb1c56786ff\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\"}}",
"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\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@4f809c287c07e3a96b0529508d180bb1c56786ff\", \"title\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\", \"url\": \"https://r8.googlesource.com/r8/+/4f809c287c07e3a96b0529508d180bb1c56786ff\"}]}, \"bot_id\": \"luci-r8-custom-ci-internal-0-mdez\", \"branch\": \"refs/heads/master\", \"builder_group\": \"internal.client.r8\", \"buildername\": \"linux-internal\", \"buildnumber\": 1304, \"internal\": \"True\", \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"4f809c287c07e3a96b0529508d180bb1c56786ff\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"2\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"r8bot-265908\"], \"gpu\": [\"none\"], \"id\": [\"luci-r8-custom-ci-internal-0-mdez\"], \"image\": [\"chrome-xenial-20090600-f98a2a06cf8\"], \"inside_docker\": [\"0\"], \"internal\": [\"true\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-2\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.r8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5518-246c570\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-a\"]}}, \"ui\": {\"info\": \"Step('Run internal') (retcode: 1)\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1599902496657504",
"status": "COMPLETED",
"status_changed_ts": "1599902497161172",
"tags": [
"build_address:luci.r8.ci/linux-internal/1304",
"builder:linux-internal",
"buildset:commit/git/4f809c287c07e3a96b0529508d180bb1c56786ff",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/4f809c287c07e3a96b0529508d180bb1c56786ff",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9034804461056647152",
"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/8869374398965364736/+/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:4e9a6fa50f533f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1599910723718588",
"url": "https://ci.chromium.org/b/8869374398965364736",
"utcnow_ts": "1599910723992811"
}
}
[11:38:44.000] Invocation finished in 2h17m50.054763538s with status FAILED