[14:26:18.929] New invocation is queued and will start shortly
[14:26:20.154] Starting the invocation (attempt 1)
[14:26:20.263] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:26:20.263] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:26:20.263] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9072926568792668112",
"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\":\"a0c396cdc9a447005c7793f32bba57bd88b1a482\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@a0c396cdc9a447005c7793f32bba57bd88b1a482\",\"title\":\"a0c396cdc9a447005c7793f32bba57bd88b1a482\",\"url\":\"https://r8.googlesource.com/r8/+/a0c396cdc9a447005c7793f32bba57bd88b1a482\"},{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://r8.googlesource.com/r8\",\"revision\":\"35e0af93408ec769f29db58fe0cd03965f5794b7\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@35e0af93408ec769f29db58fe0cd03965f5794b7\",\"title\":\"35e0af93408ec769f29db58fe0cd03965f5794b7\",\"url\":\"https://r8.googlesource.com/r8/+/35e0af93408ec769f29db58fe0cd03965f5794b7\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"35e0af93408ec769f29db58fe0cd03965f5794b7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-internal",
"scheduler_invocation_id:9072926568792668112",
"scheduler_job_id:r8/linux-internal",
"user_agent:luci-scheduler",
"buildset:commit/git/35e0af93408ec769f29db58fe0cd03965f5794b7",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/35e0af93408ec769f29db58fe0cd03965f5794b7",
"gitiles_ref:refs/heads/master"
]
}
[14:26:21.174] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"created_by": "project:r8",
"created_ts": "1563546380503408",
"id": "8907496506475541952",
"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\": \"a0c396cdc9a447005c7793f32bba57bd88b1a482\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@a0c396cdc9a447005c7793f32bba57bd88b1a482\", \"title\": \"a0c396cdc9a447005c7793f32bba57bd88b1a482\", \"url\": \"https://r8.googlesource.com/r8/+/a0c396cdc9a447005c7793f32bba57bd88b1a482\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@35e0af93408ec769f29db58fe0cd03965f5794b7\", \"title\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\", \"url\": \"https://r8.googlesource.com/r8/+/35e0af93408ec769f29db58fe0cd03965f5794b7\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1563546381024291",
"tags": [
"build_address:luci.r8.ci/linux-internal/430",
"builder:linux-internal",
"buildset:commit/git/35e0af93408ec769f29db58fe0cd03965f5794b7",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/35e0af93408ec769f29db58fe0cd03965f5794b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072926568792668112",
"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/8907496506475541952/+/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": "1563546381024411",
"url": "https://ci.chromium.org/b/8907496506475541952",
"utcnow_ts": "1563546381163444"
}
}
[14:26:21.174] Task URL: https://ci.chromium.org/b/8907496506475541952
[14:26:21.174] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:2:0) after 1m0s
[14:26:51.078] Received PubSub notification, asking Buildbucket for the build status
[14:26:51.484] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:21.638] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:2:0)
[14:27:21.638] Timer tick, asking Buildbucket for the build status
[14:27:21.719] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:21.719] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:4:0) after 1m0s
[14:28:22.126] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:4:0)
[14:28:22.127] Timer tick, asking Buildbucket for the build status
[14:28:22.237] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:22.237] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:5:0) after 1m0s
[14:29:22.402] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:5:0)
[14:29:22.402] Timer tick, asking Buildbucket for the build status
[14:29:22.700] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:22.700] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:6:0) after 1m0s
[14:30:22.912] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:6:0)
[14:30:22.912] Timer tick, asking Buildbucket for the build status
[14:30:23.411] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:23.412] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:7:0) after 1m0s
[14:31:23.532] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:7:0)
[14:31:23.532] Timer tick, asking Buildbucket for the build status
[14:31:23.610] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:23.610] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:8:0) after 1m0s
[14:32:23.745] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:8:0)
[14:32:23.745] Timer tick, asking Buildbucket for the build status
[14:32:23.938] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:23.938] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:9:0) after 1m0s
[14:33:23.979] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:9:0)
[14:33:23.979] Timer tick, asking Buildbucket for the build status
[14:33:24.164] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:24.164] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:10:0) after 1m0s
[14:34:24.197] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:10:0)
[14:34:24.197] Timer tick, asking Buildbucket for the build status
[14:34:24.310] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:24.310] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:11:0) after 1m0s
[14:35:24.393] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:11:0)
[14:35:24.393] Timer tick, asking Buildbucket for the build status
[14:35:24.601] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:24.601] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:12:0) after 1m0s
[14:36:24.671] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:12:0)
[14:36:24.671] Timer tick, asking Buildbucket for the build status
[14:36:25.433] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:25.433] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:13:0) after 1m0s
[14:37:25.498] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:13:0)
[14:37:25.498] Timer tick, asking Buildbucket for the build status
[14:37:25.659] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:25.659] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:14:0) after 1m0s
[14:38:25.704] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:14:0)
[14:38:25.704] Timer tick, asking Buildbucket for the build status
[14:38:26.958] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:26.958] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:15:0) after 1m0s
[14:39:27.111] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:15:0)
[14:39:27.111] Timer tick, asking Buildbucket for the build status
[14:39:27.429] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:27.429] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:16:0) after 1m0s
[14:40:27.491] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:16:0)
[14:40:27.491] Timer tick, asking Buildbucket for the build status
[14:40:27.612] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:27.612] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:17:0) after 1m0s
[14:41:27.715] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:17:0)
[14:41:27.715] Timer tick, asking Buildbucket for the build status
[14:41:27.925] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:27.925] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:18:0) after 1m0s
[14:42:27.974] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:18:0)
[14:42:27.974] Timer tick, asking Buildbucket for the build status
[14:42:28.214] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:28.214] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:19:0) after 1m0s
[14:43:28.266] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:19:0)
[14:43:28.266] Timer tick, asking Buildbucket for the build status
[14:43:28.407] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:28.407] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:20:0) after 1m0s
[14:44:28.499] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:20:0)
[14:44:28.499] Timer tick, asking Buildbucket for the build status
[14:44:28.726] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:28.726] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:21:0) after 1m0s
[14:45:29.759] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:21:0)
[14:45:29.759] Timer tick, asking Buildbucket for the build status
[14:45:29.849] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:29.849] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:22:0) after 1m0s
[14:46:29.843] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:22:0)
[14:46:29.843] Timer tick, asking Buildbucket for the build status
[14:46:29.924] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:29.924] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:23:0) after 1m0s
[14:47:30.034] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:23:0)
[14:47:30.034] Timer tick, asking Buildbucket for the build status
[14:47:30.199] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:30.200] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:24:0) after 1m0s
[14:48:30.313] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:24:0)
[14:48:30.313] Timer tick, asking Buildbucket for the build status
[14:48:30.468] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:30.468] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:25:0) after 1m0s
[14:49:30.500] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:25:0)
[14:49:30.501] Timer tick, asking Buildbucket for the build status
[14:49:30.961] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:30.961] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:26:0) after 1m0s
[14:50:30.990] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:26:0)
[14:50:30.990] Timer tick, asking Buildbucket for the build status
[14:50:31.162] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:31.162] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:27:0) after 1m0s
[14:51:31.184] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:27:0)
[14:51:31.184] Timer tick, asking Buildbucket for the build status
[14:51:31.293] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:31.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:28:0) after 1m0s
[14:52:31.328] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:28:0)
[14:52:31.328] Timer tick, asking Buildbucket for the build status
[14:52:31.996] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:31.996] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:29:0) after 1m0s
[14:53:32.052] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:29:0)
[14:53:32.052] Timer tick, asking Buildbucket for the build status
[14:53:32.152] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:32.152] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:30:0) after 1m0s
[14:54:32.228] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:30:0)
[14:54:32.228] Timer tick, asking Buildbucket for the build status
[14:54:32.414] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:32.414] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:31:0) after 1m0s
[14:55:32.440] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:31:0)
[14:55:32.440] Timer tick, asking Buildbucket for the build status
[14:55:32.580] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:32.580] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:32:0) after 1m0s
[14:56:32.612] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:32:0)
[14:56:32.612] Timer tick, asking Buildbucket for the build status
[14:56:32.739] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:32.739] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:33:0) after 1m0s
[14:57:32.855] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:33:0)
[14:57:32.855] Timer tick, asking Buildbucket for the build status
[14:57:32.941] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:32.941] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:34:0) after 1m0s
[14:58:33.035] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:34:0)
[14:58:33.035] Timer tick, asking Buildbucket for the build status
[14:58:33.139] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:33.139] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:35:0) after 1m0s
[14:59:33.179] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:35:0)
[14:59:33.179] Timer tick, asking Buildbucket for the build status
[14:59:33.933] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:33.933] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:36:0) after 1m0s
[15:00:34.001] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:36:0)
[15:00:34.001] Timer tick, asking Buildbucket for the build status
[15:00:34.139] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:34.139] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:37:0) after 1m0s
[15:01:34.171] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:37:0)
[15:01:34.171] Timer tick, asking Buildbucket for the build status
[15:01:34.261] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:34.261] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:38:0) after 1m0s
[15:02:34.531] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:38:0)
[15:02:34.531] Timer tick, asking Buildbucket for the build status
[15:02:34.754] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:34.754] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:39:0) after 1m0s
[15:03:34.840] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:39:0)
[15:03:34.840] Timer tick, asking Buildbucket for the build status
[15:03:34.918] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:34.918] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:40:0) after 1m0s
[15:04:34.925] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:40:0)
[15:04:34.925] Timer tick, asking Buildbucket for the build status
[15:04:35.411] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:35.411] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:41:0) after 1m0s
[15:05:35.473] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:41:0)
[15:05:35.473] Timer tick, asking Buildbucket for the build status
[15:05:35.632] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:35.632] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:42:0) after 1m0s
[15:06:35.753] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:42:0)
[15:06:35.753] Timer tick, asking Buildbucket for the build status
[15:06:36.768] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:36.768] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:43:0) after 1m0s
[15:07:36.777] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:43:0)
[15:07:36.777] Timer tick, asking Buildbucket for the build status
[15:07:37.128] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:37.128] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:44:0) after 1m0s
[15:08:37.218] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:44:0)
[15:08:37.218] Timer tick, asking Buildbucket for the build status
[15:08:38.453] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:38.453] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:45:0) after 1m0s
[15:09:38.560] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:45:0)
[15:09:38.560] Timer tick, asking Buildbucket for the build status
[15:09:38.977] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:38.977] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:46:0) after 1m0s
[15:10:39.025] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:46:0)
[15:10:39.025] Timer tick, asking Buildbucket for the build status
[15:10:40.235] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:40.235] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:47:0) after 1m0s
[15:11:40.314] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:47:0)
[15:11:40.314] Timer tick, asking Buildbucket for the build status
[15:11:40.629] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:40.629] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:48:0) after 1m0s
[15:12:40.798] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:48:0)
[15:12:40.798] Timer tick, asking Buildbucket for the build status
[15:12:40.937] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:40.937] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:49:0) after 1m0s
[15:13:41.098] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:49:0)
[15:13:41.098] Timer tick, asking Buildbucket for the build status
[15:13:41.474] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:41.474] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:50:0) after 1m0s
[15:14:41.605] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:50:0)
[15:14:41.605] Timer tick, asking Buildbucket for the build status
[15:14:41.903] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:41.903] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:51:0) after 1m0s
[15:15:41.933] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:51:0)
[15:15:41.933] Timer tick, asking Buildbucket for the build status
[15:15:42.045] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:42.045] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:52:0) after 1m0s
[15:16:42.093] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:52:0)
[15:16:42.093] Timer tick, asking Buildbucket for the build status
[15:16:42.379] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:42.379] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:53:0) after 1m0s
[15:17:42.971] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:53:0)
[15:17:42.971] Timer tick, asking Buildbucket for the build status
[15:17:43.249] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:43.249] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:54:0) after 1m0s
[15:18:43.502] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:54:0)
[15:18:43.502] Timer tick, asking Buildbucket for the build status
[15:18:43.732] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:43.732] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:55:0) after 1m0s
[15:19:43.933] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:55:0)
[15:19:43.933] Timer tick, asking Buildbucket for the build status
[15:19:44.078] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:44.078] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:56:0) after 1m0s
[15:20:44.231] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:56:0)
[15:20:44.231] Timer tick, asking Buildbucket for the build status
[15:20:44.533] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:44.533] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:57:0) after 1m0s
[15:21:44.673] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:57:0)
[15:21:44.673] Timer tick, asking Buildbucket for the build status
[15:21:44.790] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:44.790] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:58:0) after 1m0s
[15:22:45.102] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:58:0)
[15:22:45.102] Timer tick, asking Buildbucket for the build status
[15:22:45.244] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:45.244] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:59:0) after 1m0s
[15:23:45.419] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:59:0)
[15:23:45.419] Timer tick, asking Buildbucket for the build status
[15:23:45.553] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:45.553] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:60:0) after 1m0s
[15:24:45.760] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:60:0)
[15:24:45.760] Timer tick, asking Buildbucket for the build status
[15:24:45.957] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:45.957] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:61:0) after 1m0s
[15:25:46.162] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:61:0)
[15:25:46.162] Timer tick, asking Buildbucket for the build status
[15:25:46.757] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:46.757] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:62:0) after 1m0s
[15:26:46.998] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:62:0)
[15:26:46.998] Timer tick, asking Buildbucket for the build status
[15:26:47.101] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:47.101] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:63:0) after 1m0s
[15:27:47.193] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:63:0)
[15:27:47.193] Timer tick, asking Buildbucket for the build status
[15:27:47.450] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:47.450] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:64:0) after 1m0s
[15:28:47.466] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:64:0)
[15:28:47.466] Timer tick, asking Buildbucket for the build status
[15:28:47.572] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:47.572] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:65:0) after 1m0s
[15:29:47.645] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:65:0)
[15:29:47.645] Timer tick, asking Buildbucket for the build status
[15:29:48.395] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:48.395] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:66:0) after 1m0s
[15:30:48.453] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:66:0)
[15:30:48.453] Timer tick, asking Buildbucket for the build status
[15:30:48.640] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:48.640] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:67:0) after 1m0s
[15:31:48.781] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:67:0)
[15:31:48.781] Timer tick, asking Buildbucket for the build status
[15:31:49.319] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:49.319] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:68:0) after 1m0s
[15:32:49.376] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:68:0)
[15:32:49.376] Timer tick, asking Buildbucket for the build status
[15:32:49.637] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:49.637] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:69:0) after 1m0s
[15:33:49.825] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:69:0)
[15:33:49.825] Timer tick, asking Buildbucket for the build status
[15:33:49.999] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:49.999] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:70:0) after 1m0s
[15:34:50.066] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:70:0)
[15:34:50.066] Timer tick, asking Buildbucket for the build status
[15:34:50.974] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:50.974] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:71:0) after 1m0s
[15:35:51.270] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:71:0)
[15:35:51.270] Timer tick, asking Buildbucket for the build status
[15:35:51.866] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:51.866] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:72:0) after 1m0s
[15:36:51.995] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:72:0)
[15:36:51.995] Timer tick, asking Buildbucket for the build status
[15:36:52.396] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:52.396] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:73:0) after 1m0s
[15:37:52.434] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:73:0)
[15:37:52.434] Timer tick, asking Buildbucket for the build status
[15:37:52.706] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:52.706] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:74:0) after 1m0s
[15:38:52.866] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:74:0)
[15:38:52.866] Timer tick, asking Buildbucket for the build status
[15:38:53.025] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:53.025] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:75:0) after 1m0s
[15:39:53.180] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:75:0)
[15:39:53.180] Timer tick, asking Buildbucket for the build status
[15:39:53.558] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:53.558] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:76:0) after 1m0s
[15:40:53.625] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:76:0)
[15:40:53.625] Timer tick, asking Buildbucket for the build status
[15:40:53.716] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:53.717] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:77:0) after 1m0s
[15:41:53.779] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:77:0)
[15:41:53.779] Timer tick, asking Buildbucket for the build status
[15:41:53.883] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:53.883] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:78:0) after 1m0s
[15:42:53.962] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:78:0)
[15:42:53.963] Timer tick, asking Buildbucket for the build status
[15:42:54.175] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:54.175] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:79:0) after 1m0s
[15:43:54.343] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:79:0)
[15:43:54.343] Timer tick, asking Buildbucket for the build status
[15:43:55.495] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:55.495] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:80:0) after 1m0s
[15:44:55.582] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:80:0)
[15:44:55.582] Timer tick, asking Buildbucket for the build status
[15:44:56.273] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:56.273] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:81:0) after 1m0s
[15:45:56.532] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:81:0)
[15:45:56.533] Timer tick, asking Buildbucket for the build status
[15:45:57.124] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:57.124] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:82:0) after 1m0s
[15:46:57.288] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:82:0)
[15:46:57.288] Timer tick, asking Buildbucket for the build status
[15:46:57.406] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:57.406] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:83:0) after 1m0s
[15:47:57.546] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:83:0)
[15:47:57.546] Timer tick, asking Buildbucket for the build status
[15:47:58.326] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:58.326] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:84:0) after 1m0s
[15:48:58.495] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:84:0)
[15:48:58.495] Timer tick, asking Buildbucket for the build status
[15:48:58.695] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:58.695] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:85:0) after 1m0s
[15:49:58.935] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:85:0)
[15:49:58.935] Timer tick, asking Buildbucket for the build status
[15:49:59.445] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:59.445] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:86:0) after 1m0s
[15:50:59.578] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:86:0)
[15:50:59.578] Timer tick, asking Buildbucket for the build status
[15:51:00.686] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:00.686] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:87:0) after 1m0s
[15:52:00.751] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:87:0)
[15:52:00.751] Timer tick, asking Buildbucket for the build status
[15:52:00.924] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:00.924] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:88:0) after 1m0s
[15:53:00.982] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:88:0)
[15:53:00.982] Timer tick, asking Buildbucket for the build status
[15:53:01.080] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:01.080] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:89:0) after 1m0s
[15:54:01.172] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:89:0)
[15:54:01.172] Timer tick, asking Buildbucket for the build status
[15:54:01.541] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:01.541] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:90:0) after 1m0s
[15:55:01.595] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:90:0)
[15:55:01.595] Timer tick, asking Buildbucket for the build status
[15:55:01.728] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:01.728] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:91:0) after 1m0s
[15:56:01.839] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:91:0)
[15:56:01.839] Timer tick, asking Buildbucket for the build status
[15:56:02.030] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:02.030] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:92:0) after 1m0s
[15:57:02.227] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:92:0)
[15:57:02.227] Timer tick, asking Buildbucket for the build status
[15:57:03.220] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:03.220] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:93:0) after 1m0s
[15:58:03.354] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:93:0)
[15:58:03.354] Timer tick, asking Buildbucket for the build status
[15:58:04.735] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:04.735] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:94:0) after 1m0s
[15:59:04.902] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:94:0)
[15:59:04.902] Timer tick, asking Buildbucket for the build status
[15:59:05.004] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:05.004] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:95:0) after 1m0s
[16:00:05.260] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:95:0)
[16:00:05.260] Timer tick, asking Buildbucket for the build status
[16:00:05.923] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:05.923] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:96:0) after 1m0s
[16:01:06.216] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:96:0)
[16:01:06.216] Timer tick, asking Buildbucket for the build status
[16:01:06.692] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:06.692] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:97:0) after 1m0s
[16:02:06.890] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:97:0)
[16:02:06.890] Timer tick, asking Buildbucket for the build status
[16:02:07.675] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:07.675] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:98:0) after 1m0s
[16:03:07.786] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:98:0)
[16:03:07.786] Timer tick, asking Buildbucket for the build status
[16:03:07.942] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:07.942] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:99:0) after 1m0s
[16:04:08.103] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:99:0)
[16:04:08.103] Timer tick, asking Buildbucket for the build status
[16:04:08.457] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:08.457] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:100:0) after 1m0s
[16:05:08.625] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:100:0)
[16:05:08.625] Timer tick, asking Buildbucket for the build status
[16:05:10.112] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:10.112] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:101:0) after 1m0s
[16:06:10.242] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:101:0)
[16:06:10.242] Timer tick, asking Buildbucket for the build status
[16:06:10.585] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:10.585] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:102:0) after 1m0s
[16:07:10.680] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:102:0)
[16:07:10.680] Timer tick, asking Buildbucket for the build status
[16:07:12.524] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:12.524] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:103:0) after 1m0s
[16:08:12.545] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:103:0)
[16:08:12.545] Timer tick, asking Buildbucket for the build status
[16:08:13.510] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:13.510] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:104:0) after 1m0s
[16:09:13.587] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:104:0)
[16:09:13.587] Timer tick, asking Buildbucket for the build status
[16:09:13.838] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:13.838] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:105:0) after 1m0s
[16:10:13.884] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:105:0)
[16:10:13.884] Timer tick, asking Buildbucket for the build status
[16:10:14.950] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:14.950] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:106:0) after 1m0s
[16:11:14.976] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:106:0)
[16:11:14.976] Timer tick, asking Buildbucket for the build status
[16:11:15.372] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:15.372] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:107:0) after 1m0s
[16:12:15.420] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:107:0)
[16:12:15.420] Timer tick, asking Buildbucket for the build status
[16:12:16.469] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:16.469] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:108:0) after 1m0s
[16:13:16.526] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:108:0)
[16:13:16.526] Timer tick, asking Buildbucket for the build status
[16:13:16.729] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:16.729] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:109:0) after 1m0s
[16:14:16.815] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:109:0)
[16:14:16.815] Timer tick, asking Buildbucket for the build status
[16:14:16.972] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:16.972] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:110:0) after 1m0s
[16:15:17.024] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:110:0)
[16:15:17.024] Timer tick, asking Buildbucket for the build status
[16:15:17.207] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:17.207] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:111:0) after 1m0s
[16:16:17.333] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:111:0)
[16:16:17.333] Timer tick, asking Buildbucket for the build status
[16:16:17.625] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:17.625] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:112:0) after 1m0s
[16:17:17.736] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:112:0)
[16:17:17.736] Timer tick, asking Buildbucket for the build status
[16:17:17.922] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:17.922] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:113:0) after 1m0s
[16:18:17.974] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:113:0)
[16:18:17.974] Timer tick, asking Buildbucket for the build status
[16:18:18.220] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:18.220] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:114:0) after 1m0s
[16:19:18.393] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:114:0)
[16:19:18.393] Timer tick, asking Buildbucket for the build status
[16:19:18.874] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:18.874] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:115:0) after 1m0s
[16:20:18.975] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:115:0)
[16:20:18.975] Timer tick, asking Buildbucket for the build status
[16:20:20.362] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:20.362] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:116:0) after 1m0s
[16:21:20.448] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:116:0)
[16:21:20.448] Timer tick, asking Buildbucket for the build status
[16:21:20.590] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:20.590] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:117:0) after 1m0s
[16:22:20.648] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:117:0)
[16:22:20.648] Timer tick, asking Buildbucket for the build status
[16:22:20.891] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:20.891] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:118:0) after 1m0s
[16:23:20.963] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:118:0)
[16:23:20.963] Timer tick, asking Buildbucket for the build status
[16:23:21.327] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:21.327] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:119:0) after 1m0s
[16:24:21.423] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:119:0)
[16:24:21.423] Timer tick, asking Buildbucket for the build status
[16:24:22.313] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:22.313] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:120:0) after 1m0s
[16:25:22.370] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:120:0)
[16:25:22.370] Timer tick, asking Buildbucket for the build status
[16:25:26.529] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:26.529] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:121:0) after 1m0s
[16:26:26.571] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:121:0)
[16:26:26.571] Timer tick, asking Buildbucket for the build status
[16:26:26.734] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:26.734] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:122:0) after 1m0s
[16:27:26.867] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:122:0)
[16:27:26.867] Timer tick, asking Buildbucket for the build status
[16:27:27.027] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:27.027] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:123:0) after 1m0s
[16:28:27.281] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:123:0)
[16:28:27.281] Timer tick, asking Buildbucket for the build status
[16:28:27.560] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:27.560] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:124:0) after 1m0s
[16:29:27.725] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:124:0)
[16:29:27.725] Timer tick, asking Buildbucket for the build status
[16:29:27.970] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:27.970] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:125:0) after 1m0s
[16:30:28.145] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:125:0)
[16:30:28.145] Timer tick, asking Buildbucket for the build status
[16:30:28.428] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:28.428] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:126:0) after 1m0s
[16:31:28.540] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:126:0)
[16:31:28.540] Timer tick, asking Buildbucket for the build status
[16:31:28.675] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:28.675] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:127:0) after 1m0s
[16:32:28.709] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:127:0)
[16:32:28.709] Timer tick, asking Buildbucket for the build status
[16:32:30.147] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:30.147] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:128:0) after 1m0s
[16:33:30.392] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:128:0)
[16:33:30.392] Timer tick, asking Buildbucket for the build status
[16:33:30.650] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:30.650] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:129:0) after 1m0s
[16:34:30.843] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:129:0)
[16:34:30.844] Timer tick, asking Buildbucket for the build status
[16:34:31.047] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:31.047] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:130:0) after 1m0s
[16:35:31.074] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:130:0)
[16:35:31.074] Timer tick, asking Buildbucket for the build status
[16:35:31.371] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:31.371] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:131:0) after 1m0s
[16:36:31.437] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:131:0)
[16:36:31.437] Timer tick, asking Buildbucket for the build status
[16:36:31.569] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:31.569] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:132:0) after 1m0s
[16:37:31.630] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:132:0)
[16:37:31.630] Timer tick, asking Buildbucket for the build status
[16:37:31.794] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:31.794] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:133:0) after 1m0s
[16:38:31.957] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:133:0)
[16:38:31.957] Timer tick, asking Buildbucket for the build status
[16:38:32.136] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:32.136] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:134:0) after 1m0s
[16:39:32.221] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:134:0)
[16:39:32.221] Timer tick, asking Buildbucket for the build status
[16:39:32.352] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:32.352] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:135:0) after 1m0s
[16:40:32.389] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:135:0)
[16:40:32.389] Timer tick, asking Buildbucket for the build status
[16:40:32.500] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:32.500] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:136:0) after 1m0s
[16:41:32.697] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:136:0)
[16:41:32.697] Timer tick, asking Buildbucket for the build status
[16:41:32.923] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:32.923] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:137:0) after 1m0s
[16:42:33.130] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:137:0)
[16:42:33.130] Timer tick, asking Buildbucket for the build status
[16:42:33.348] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:33.348] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:138:0) after 1m0s
[16:43:33.426] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:138:0)
[16:43:33.426] Timer tick, asking Buildbucket for the build status
[16:43:34.149] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:34.149] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:139:0) after 1m0s
[16:44:34.173] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:139:0)
[16:44:34.173] Timer tick, asking Buildbucket for the build status
[16:44:34.400] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:34.400] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:140:0) after 1m0s
[16:45:34.478] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:140:0)
[16:45:34.478] Timer tick, asking Buildbucket for the build status
[16:45:35.311] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:35.311] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:141:0) after 1m0s
[16:46:35.402] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:141:0)
[16:46:35.402] Timer tick, asking Buildbucket for the build status
[16:46:35.730] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:35.730] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:142:0) after 1m0s
[16:47:35.851] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:142:0)
[16:47:35.851] Timer tick, asking Buildbucket for the build status
[16:47:36.144] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:36.144] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:143:0) after 1m0s
[16:48:36.219] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:143:0)
[16:48:36.219] Timer tick, asking Buildbucket for the build status
[16:48:36.338] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:36.338] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:144:0) after 1m0s
[16:49:36.412] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:144:0)
[16:49:36.412] Timer tick, asking Buildbucket for the build status
[16:49:38.582] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:38.582] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:145:0) after 1m0s
[16:50:38.692] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:145:0)
[16:50:38.692] Timer tick, asking Buildbucket for the build status
[16:50:39.052] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:39.052] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:146:0) after 1m0s
[16:51:39.090] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:146:0)
[16:51:39.090] Timer tick, asking Buildbucket for the build status
[16:51:39.282] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:39.282] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:147:0) after 1m0s
[16:52:39.323] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:147:0)
[16:52:39.323] Timer tick, asking Buildbucket for the build status
[16:52:39.414] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:39.414] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:148:0) after 1m0s
[16:53:39.538] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:148:0)
[16:53:39.538] Timer tick, asking Buildbucket for the build status
[16:53:39.827] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:39.827] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:149:0) after 1m0s
[16:54:39.928] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:149:0)
[16:54:39.928] Timer tick, asking Buildbucket for the build status
[16:54:40.028] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:40.028] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:150:0) after 1m0s
[16:55:40.188] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:150:0)
[16:55:40.188] Timer tick, asking Buildbucket for the build status
[16:55:40.292] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:40.292] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:151:0) after 1m0s
[16:56:40.371] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:151:0)
[16:56:40.371] Timer tick, asking Buildbucket for the build status
[16:56:40.640] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:40.640] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:152:0) after 1m0s
[16:57:40.712] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:152:0)
[16:57:40.712] Timer tick, asking Buildbucket for the build status
[16:57:41.308] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:41.308] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:153:0) after 1m0s
[16:58:41.287] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:153:0)
[16:58:41.287] Timer tick, asking Buildbucket for the build status
[16:58:41.479] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:41.479] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:154:0) after 1m0s
[16:59:41.577] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:154:0)
[16:59:41.577] Timer tick, asking Buildbucket for the build status
[16:59:41.663] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:41.663] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:155:0) after 1m0s
[17:00:41.770] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:155:0)
[17:00:41.770] Timer tick, asking Buildbucket for the build status
[17:00:43.210] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:43.210] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:156:0) after 1m0s
[17:01:43.263] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:156:0)
[17:01:43.263] Timer tick, asking Buildbucket for the build status
[17:01:43.968] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:43.968] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:157:0) after 1m0s
[17:02:44.114] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:157:0)
[17:02:44.114] Timer tick, asking Buildbucket for the build status
[17:02:44.233] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:44.233] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:158:0) after 1m0s
[17:03:44.327] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:158:0)
[17:03:44.327] Timer tick, asking Buildbucket for the build status
[17:03:44.473] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:44.473] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:159:0) after 1m0s
[17:04:44.578] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:159:0)
[17:04:44.578] Timer tick, asking Buildbucket for the build status
[17:04:44.681] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:44.681] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:160:0) after 1m0s
[17:05:44.982] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:160:0)
[17:05:44.982] Timer tick, asking Buildbucket for the build status
[17:05:45.161] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:45.161] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:161:0) after 1m0s
[17:06:45.277] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:161:0)
[17:06:45.277] Timer tick, asking Buildbucket for the build status
[17:06:45.871] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:45.871] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:162:0) after 1m0s
[17:07:45.904] Handling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:162:0)
[17:07:45.904] Timer tick, asking Buildbucket for the build status
[17:07:46.391] Build 8907496506475541952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:46.391] Scheduling timer "check-buildbucket-build-status" (r8/linux-internal:9072926568792668112:163:0) after 1m0s
[17:08:20.332] Received PubSub notification, asking Buildbucket for the build status
[17:08:20.416] Build 8907496506475541952: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[17:08:20.416] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"completed_ts": "1563556098382469",
"created_by": "project:r8",
"created_ts": "1563546380503408",
"failure_reason": "BUILD_FAILURE",
"id": "8907496506475541952",
"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\": \"a0c396cdc9a447005c7793f32bba57bd88b1a482\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@a0c396cdc9a447005c7793f32bba57bd88b1a482\", \"title\": \"a0c396cdc9a447005c7793f32bba57bd88b1a482\", \"url\": \"https://r8.googlesource.com/r8/+/a0c396cdc9a447005c7793f32bba57bd88b1a482\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@35e0af93408ec769f29db58fe0cd03965f5794b7\", \"title\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\", \"url\": \"https://r8.googlesource.com/r8/+/35e0af93408ec769f29db58fe0cd03965f5794b7\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\"}}",
"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\": \"a0c396cdc9a447005c7793f32bba57bd88b1a482\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@a0c396cdc9a447005c7793f32bba57bd88b1a482\", \"title\": \"a0c396cdc9a447005c7793f32bba57bd88b1a482\", \"url\": \"https://r8.googlesource.com/r8/+/a0c396cdc9a447005c7793f32bba57bd88b1a482\"}, {\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@35e0af93408ec769f29db58fe0cd03965f5794b7\", \"title\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\", \"url\": \"https://r8.googlesource.com/r8/+/35e0af93408ec769f29db58fe0cd03965f5794b7\"}]}, \"bot_id\": \"luci-r8-ci-internal-0-9qfb\", \"branch\": \"refs/heads/master\", \"buildername\": \"linux-internal\", \"buildnumber\": 430, \"internal\": \"True\", \"mastername\": \"internal.client.r8\", \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"35e0af93408ec769f29db58fe0cd03965f5794b7\"}, \"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-9qfb\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"internal\": [\"true\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.r8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4370-0e704a3\"], \"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": "1563546409487896",
"status": "COMPLETED",
"status_changed_ts": "1563546410035033",
"tags": [
"build_address:luci.r8.ci/linux-internal/430",
"builder:linux-internal",
"buildset:commit/git/35e0af93408ec769f29db58fe0cd03965f5794b7",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/35e0af93408ec769f29db58fe0cd03965f5794b7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9072926568792668112",
"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/8907496506475541952/+/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:462371f592523810",
"user_agent:luci-scheduler"
],
"updated_ts": "1563556098805717",
"url": "https://ci.chromium.org/b/8907496506475541952",
"utcnow_ts": "1563556100402353"
}
}
[17:08:20.416] Invocation finished in 2h42m1.505664952s with status FAILED