[08:59:53.651] New invocation is queued and will start shortly
[08:59:54.854] Starting the invocation (attempt 1)
[08:59:54.884] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:59:54.884] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:59:54.884] Buildbucket request:
{
"bucket": "luci.r8.ci",
"client_operation_id": "9018770119533149712",
"parameters_json": "{\"builder_name\":\"linux-run-on-app-dump\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://r8.googlesource.com/r8\",\"revision\":\"5e090c7c100c3200841f5859e986a29a2e0eff0b\"},\"id\":\"https://r8.googlesource.com/r8/+/refs/heads/master@5e090c7c100c3200841f5859e986a29a2e0eff0b\",\"title\":\"5e090c7c100c3200841f5859e986a29a2e0eff0b\",\"url\":\"https://r8.googlesource.com/r8/+/5e090c7c100c3200841f5859e986a29a2e0eff0b\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://r8.googlesource.com/r8\",\"revision\":\"5e090c7c100c3200841f5859e986a29a2e0eff0b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:linux-run-on-app-dump",
"scheduler_invocation_id:9018770119533149712",
"scheduler_job_id:r8/linux-run-on-app-dump",
"user_agent:luci-scheduler",
"buildset:commit/git/5e090c7c100c3200841f5859e986a29a2e0eff0b",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/5e090c7c100c3200841f5859e986a29a2e0eff0b",
"gitiles_ref:refs/heads/master"
]
}
[08:59:55.666] Buildbucket response:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"created_by": "project:r8",
"created_ts": "1615193994970275",
"id": "8853340057489022224",
"parameters_json": "{\"builder_name\": \"linux-run-on-app-dump\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@5e090c7c100c3200841f5859e986a29a2e0eff0b\", \"title\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\", \"url\": \"https://r8.googlesource.com/r8/+/5e090c7c100c3200841f5859e986a29a2e0eff0b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\"}}",
"project": "r8",
"result_details_json": "{\"properties\": {}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615193995499500",
"tags": [
"build_address:luci.r8.ci/linux-run-on-app-dump/583",
"builder:linux-run-on-app-dump",
"buildset:commit/git/5e090c7c100c3200841f5859e986a29a2e0eff0b",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/5e090c7c100c3200841f5859e986a29a2e0eff0b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018770119533149712",
"scheduler_job_id:r8/linux-run-on-app-dump",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8853340057489022224/+/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": "1615193995501067",
"url": "https://ci.chromium.org/b/8853340057489022224",
"utcnow_ts": "1615193995659280"
}
}
[08:59:55.666] Task URL: https://ci.chromium.org/b/8853340057489022224
[08:59:55.666] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:2:0) after 1m0s
[09:00:14.402] Received PubSub notification, asking Buildbucket for the build status
[09:00:14.500] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:55.950] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:2:0)
[09:00:55.950] Timer tick, asking Buildbucket for the build status
[09:00:56.057] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:56.057] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:4:0) after 1m0s
[09:01:56.231] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:4:0)
[09:01:56.231] Timer tick, asking Buildbucket for the build status
[09:01:56.302] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:56.302] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:5:0) after 1m0s
[09:02:56.468] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:5:0)
[09:02:56.468] Timer tick, asking Buildbucket for the build status
[09:02:56.532] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:56.532] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:6:0) after 1m0s
[09:03:56.511] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:6:0)
[09:03:56.511] Timer tick, asking Buildbucket for the build status
[09:03:56.584] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:56.584] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:7:0) after 1m0s
[09:04:56.643] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:7:0)
[09:04:56.643] Timer tick, asking Buildbucket for the build status
[09:04:56.721] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:56.721] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:8:0) after 1m0s
[09:05:56.791] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:8:0)
[09:05:56.791] Timer tick, asking Buildbucket for the build status
[09:05:56.850] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:56.850] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:9:0) after 1m0s
[09:06:56.989] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:9:0)
[09:06:56.989] Timer tick, asking Buildbucket for the build status
[09:06:57.057] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:57.057] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:10:0) after 1m0s
[09:07:57.163] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:10:0)
[09:07:57.163] Timer tick, asking Buildbucket for the build status
[09:07:57.229] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:57.229] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:11:0) after 1m0s
[09:08:57.249] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:11:0)
[09:08:57.249] Timer tick, asking Buildbucket for the build status
[09:08:57.330] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:57.330] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:12:0) after 1m0s
[09:09:57.422] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:12:0)
[09:09:57.422] Timer tick, asking Buildbucket for the build status
[09:09:57.520] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:57.520] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:13:0) after 1m0s
[09:10:57.611] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:13:0)
[09:10:57.611] Timer tick, asking Buildbucket for the build status
[09:10:57.730] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:57.730] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:14:0) after 1m0s
[09:11:57.772] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:14:0)
[09:11:57.772] Timer tick, asking Buildbucket for the build status
[09:11:57.867] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:57.867] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:15:0) after 1m0s
[09:12:57.914] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:15:0)
[09:12:57.914] Timer tick, asking Buildbucket for the build status
[09:12:57.984] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:57.984] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:16:0) after 1m0s
[09:13:58.003] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:16:0)
[09:13:58.003] Timer tick, asking Buildbucket for the build status
[09:13:58.083] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:58.083] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:17:0) after 1m0s
[09:14:58.159] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:17:0)
[09:14:58.159] Timer tick, asking Buildbucket for the build status
[09:14:58.241] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:58.241] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:18:0) after 1m0s
[09:15:58.394] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:18:0)
[09:15:58.394] Timer tick, asking Buildbucket for the build status
[09:15:58.471] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:58.471] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:19:0) after 1m0s
[09:16:58.909] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:19:0)
[09:16:58.909] Timer tick, asking Buildbucket for the build status
[09:16:58.971] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:58.971] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:20:0) after 1m0s
[09:17:59.093] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:20:0)
[09:17:59.093] Timer tick, asking Buildbucket for the build status
[09:17:59.403] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:59.403] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:21:0) after 1m0s
[09:18:59.504] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:21:0)
[09:18:59.504] Timer tick, asking Buildbucket for the build status
[09:18:59.573] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:59.573] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:22:0) after 1m0s
[09:19:59.575] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:22:0)
[09:19:59.575] Timer tick, asking Buildbucket for the build status
[09:19:59.663] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:59.663] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:23:0) after 1m0s
[09:20:59.677] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:23:0)
[09:20:59.677] Timer tick, asking Buildbucket for the build status
[09:20:59.742] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:59.742] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:24:0) after 1m0s
[09:21:59.815] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:24:0)
[09:21:59.815] Timer tick, asking Buildbucket for the build status
[09:22:00.181] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:00.181] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:25:0) after 1m0s
[09:23:00.195] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:25:0)
[09:23:00.195] Timer tick, asking Buildbucket for the build status
[09:23:00.258] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:00.258] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:26:0) after 1m0s
[09:24:00.414] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:26:0)
[09:24:00.414] Timer tick, asking Buildbucket for the build status
[09:24:00.535] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:00.535] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:27:0) after 1m0s
[09:25:01.045] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:27:0)
[09:25:01.045] Timer tick, asking Buildbucket for the build status
[09:25:01.309] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:01.309] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:28:0) after 1m0s
[09:26:02.084] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:28:0)
[09:26:02.084] Timer tick, asking Buildbucket for the build status
[09:26:02.220] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:02.220] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:29:0) after 1m0s
[09:27:02.276] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:29:0)
[09:27:02.276] Timer tick, asking Buildbucket for the build status
[09:27:02.501] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:02.501] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:30:0) after 1m0s
[09:28:02.528] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:30:0)
[09:28:02.528] Timer tick, asking Buildbucket for the build status
[09:28:02.599] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:02.600] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:31:0) after 1m0s
[09:29:02.642] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:31:0)
[09:29:02.643] Timer tick, asking Buildbucket for the build status
[09:29:02.729] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:02.729] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:32:0) after 1m0s
[09:30:02.862] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:32:0)
[09:30:02.862] Timer tick, asking Buildbucket for the build status
[09:30:02.963] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:02.963] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:33:0) after 1m0s
[09:31:02.941] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:33:0)
[09:31:02.941] Timer tick, asking Buildbucket for the build status
[09:31:04.310] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:04.310] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:34:0) after 1m0s
[09:32:04.345] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:34:0)
[09:32:04.345] Timer tick, asking Buildbucket for the build status
[09:32:04.425] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:04.426] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:35:0) after 1m0s
[09:33:04.562] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:35:0)
[09:33:04.562] Timer tick, asking Buildbucket for the build status
[09:33:04.815] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:04.815] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:36:0) after 1m0s
[09:34:04.854] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:36:0)
[09:34:04.854] Timer tick, asking Buildbucket for the build status
[09:34:05.011] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:05.012] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:37:0) after 1m0s
[09:35:05.085] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:37:0)
[09:35:05.085] Timer tick, asking Buildbucket for the build status
[09:35:05.408] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:05.408] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:38:0) after 1m0s
[09:36:05.552] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:38:0)
[09:36:05.552] Timer tick, asking Buildbucket for the build status
[09:36:05.754] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:05.754] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:39:0) after 1m0s
[09:37:05.967] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:39:0)
[09:37:05.967] Timer tick, asking Buildbucket for the build status
[09:37:06.055] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:06.055] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:40:0) after 1m0s
[09:38:06.081] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:40:0)
[09:38:06.081] Timer tick, asking Buildbucket for the build status
[09:38:06.413] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:06.413] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:41:0) after 1m0s
[09:39:06.524] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:41:0)
[09:39:06.524] Timer tick, asking Buildbucket for the build status
[09:39:06.588] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:06.588] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:42:0) after 1m0s
[09:40:06.905] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:42:0)
[09:40:06.905] Timer tick, asking Buildbucket for the build status
[09:40:07.009] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:07.009] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:43:0) after 1m0s
[09:41:07.171] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:43:0)
[09:41:07.171] Timer tick, asking Buildbucket for the build status
[09:41:07.607] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:07.607] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:44:0) after 1m0s
[09:42:07.659] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:44:0)
[09:42:07.659] Timer tick, asking Buildbucket for the build status
[09:42:07.753] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:07.753] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:45:0) after 1m0s
[09:43:07.786] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:45:0)
[09:43:07.786] Timer tick, asking Buildbucket for the build status
[09:43:07.957] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:07.957] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:46:0) after 1m0s
[09:44:08.039] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:46:0)
[09:44:08.039] Timer tick, asking Buildbucket for the build status
[09:44:08.114] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:08.114] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:47:0) after 1m0s
[09:45:08.357] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:47:0)
[09:45:08.357] Timer tick, asking Buildbucket for the build status
[09:45:08.568] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:08.568] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:48:0) after 1m0s
[09:46:08.596] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:48:0)
[09:46:08.596] Timer tick, asking Buildbucket for the build status
[09:46:08.747] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:08.747] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:49:0) after 1m0s
[09:47:08.880] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:49:0)
[09:47:08.880] Timer tick, asking Buildbucket for the build status
[09:47:09.047] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:09.047] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:50:0) after 1m0s
[09:48:09.070] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:50:0)
[09:48:09.070] Timer tick, asking Buildbucket for the build status
[09:48:09.210] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:09.210] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:51:0) after 1m0s
[09:49:09.257] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:51:0)
[09:49:09.257] Timer tick, asking Buildbucket for the build status
[09:49:09.330] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:09.330] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:52:0) after 1m0s
[09:50:09.346] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:52:0)
[09:50:09.346] Timer tick, asking Buildbucket for the build status
[09:50:09.475] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:09.475] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:53:0) after 1m0s
[09:51:09.541] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:53:0)
[09:51:09.541] Timer tick, asking Buildbucket for the build status
[09:51:09.655] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:09.655] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:54:0) after 1m0s
[09:52:09.678] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:54:0)
[09:52:09.678] Timer tick, asking Buildbucket for the build status
[09:52:09.767] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:09.767] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:55:0) after 1m0s
[09:53:09.820] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:55:0)
[09:53:09.820] Timer tick, asking Buildbucket for the build status
[09:53:09.952] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:09.952] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:56:0) after 1m0s
[09:54:10.008] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:56:0)
[09:54:10.008] Timer tick, asking Buildbucket for the build status
[09:54:10.077] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:10.077] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:57:0) after 1m0s
[09:55:10.092] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:57:0)
[09:55:10.092] Timer tick, asking Buildbucket for the build status
[09:55:10.191] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:10.191] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:58:0) after 1m0s
[09:56:10.243] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:58:0)
[09:56:10.243] Timer tick, asking Buildbucket for the build status
[09:56:10.335] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:10.335] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:59:0) after 1m0s
[09:57:10.375] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:59:0)
[09:57:10.375] Timer tick, asking Buildbucket for the build status
[09:57:10.448] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:10.448] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:60:0) after 1m0s
[09:58:10.595] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:60:0)
[09:58:10.595] Timer tick, asking Buildbucket for the build status
[09:58:10.665] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:10.665] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:61:0) after 1m0s
[09:59:11.092] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:61:0)
[09:59:11.092] Timer tick, asking Buildbucket for the build status
[09:59:11.179] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:11.179] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:62:0) after 1m0s
[10:00:11.503] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:62:0)
[10:00:11.503] Timer tick, asking Buildbucket for the build status
[10:00:13.891] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:13.891] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:63:0) after 1m0s
[10:01:13.945] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:63:0)
[10:01:13.945] Timer tick, asking Buildbucket for the build status
[10:01:14.389] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:14.389] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:64:0) after 1m0s
[10:02:15.459] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:64:0)
[10:02:15.459] Timer tick, asking Buildbucket for the build status
[10:02:15.535] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:15.535] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:65:0) after 1m0s
[10:03:15.552] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:65:0)
[10:03:15.552] Timer tick, asking Buildbucket for the build status
[10:03:15.688] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:15.688] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:66:0) after 1m0s
[10:04:15.703] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:66:0)
[10:04:15.703] Timer tick, asking Buildbucket for the build status
[10:04:15.858] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:15.858] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:67:0) after 1m0s
[10:05:15.921] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:67:0)
[10:05:15.921] Timer tick, asking Buildbucket for the build status
[10:05:16.392] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:16.392] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:68:0) after 1m0s
[10:06:16.492] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:68:0)
[10:06:16.492] Timer tick, asking Buildbucket for the build status
[10:06:16.752] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:16.752] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:69:0) after 1m0s
[10:07:16.771] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:69:0)
[10:07:16.771] Timer tick, asking Buildbucket for the build status
[10:07:16.993] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:16.993] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:70:0) after 1m0s
[10:08:17.009] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:70:0)
[10:08:17.009] Timer tick, asking Buildbucket for the build status
[10:08:17.132] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:17.132] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:71:0) after 1m0s
[10:09:17.199] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:71:0)
[10:09:17.199] Timer tick, asking Buildbucket for the build status
[10:09:17.269] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:17.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:72:0) after 1m0s
[10:10:17.450] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:72:0)
[10:10:17.450] Timer tick, asking Buildbucket for the build status
[10:10:17.635] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:17.636] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:73:0) after 1m0s
[10:11:17.745] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:73:0)
[10:11:17.745] Timer tick, asking Buildbucket for the build status
[10:11:17.818] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:17.818] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:74:0) after 1m0s
[10:12:18.067] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:74:0)
[10:12:18.067] Timer tick, asking Buildbucket for the build status
[10:12:18.269] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:18.269] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:75:0) after 1m0s
[10:13:18.387] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:75:0)
[10:13:18.387] Timer tick, asking Buildbucket for the build status
[10:13:18.450] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:18.450] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:76:0) after 1m0s
[10:14:18.542] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:76:0)
[10:14:18.542] Timer tick, asking Buildbucket for the build status
[10:14:18.927] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:18.927] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:77:0) after 1m0s
[10:15:19.674] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:77:0)
[10:15:19.675] Timer tick, asking Buildbucket for the build status
[10:15:19.862] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:19.862] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:78:0) after 1m0s
[10:16:19.865] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:78:0)
[10:16:19.865] Timer tick, asking Buildbucket for the build status
[10:16:19.930] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:19.930] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:79:0) after 1m0s
[10:17:19.952] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:79:0)
[10:17:19.953] Timer tick, asking Buildbucket for the build status
[10:17:20.021] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:20.021] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:80:0) after 1m0s
[10:18:20.089] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:80:0)
[10:18:20.089] Timer tick, asking Buildbucket for the build status
[10:18:20.145] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:20.145] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:81:0) after 1m0s
[10:19:20.221] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:81:0)
[10:19:20.221] Timer tick, asking Buildbucket for the build status
[10:19:20.441] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:20.441] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:82:0) after 1m0s
[10:20:20.465] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:82:0)
[10:20:20.465] Timer tick, asking Buildbucket for the build status
[10:20:20.547] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:20.547] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:83:0) after 1m0s
[10:21:20.630] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:83:0)
[10:21:20.630] Timer tick, asking Buildbucket for the build status
[10:21:20.705] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:20.705] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:84:0) after 1m0s
[10:22:20.980] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:84:0)
[10:22:20.980] Timer tick, asking Buildbucket for the build status
[10:22:21.048] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:21.048] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:85:0) after 1m0s
[10:23:21.720] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:85:0)
[10:23:21.720] Timer tick, asking Buildbucket for the build status
[10:23:21.799] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:21.799] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:86:0) after 1m0s
[10:24:21.976] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:86:0)
[10:24:21.976] Timer tick, asking Buildbucket for the build status
[10:24:22.087] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:22.087] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:87:0) after 1m0s
[10:25:22.201] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:87:0)
[10:25:22.201] Timer tick, asking Buildbucket for the build status
[10:25:22.283] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:22.283] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:88:0) after 1m0s
[10:26:22.362] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:88:0)
[10:26:22.362] Timer tick, asking Buildbucket for the build status
[10:26:22.877] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:22.877] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:89:0) after 1m0s
[10:27:22.873] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:89:0)
[10:27:22.873] Timer tick, asking Buildbucket for the build status
[10:27:22.941] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:22.941] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:90:0) after 1m0s
[10:28:22.978] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:90:0)
[10:28:22.978] Timer tick, asking Buildbucket for the build status
[10:28:23.076] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:23.076] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:91:0) after 1m0s
[10:29:23.125] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:91:0)
[10:29:23.125] Timer tick, asking Buildbucket for the build status
[10:29:23.238] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:23.238] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:92:0) after 1m0s
[10:30:23.289] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:92:0)
[10:30:23.289] Timer tick, asking Buildbucket for the build status
[10:30:24.293] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:24.293] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:93:0) after 1m0s
[10:31:24.376] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:93:0)
[10:31:24.376] Timer tick, asking Buildbucket for the build status
[10:31:24.552] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:24.552] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:94:0) after 1m0s
[10:32:24.598] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:94:0)
[10:32:24.598] Timer tick, asking Buildbucket for the build status
[10:32:24.689] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:24.689] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:95:0) after 1m0s
[10:33:24.721] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:95:0)
[10:33:24.721] Timer tick, asking Buildbucket for the build status
[10:33:24.819] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:24.819] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:96:0) after 1m0s
[10:34:24.875] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:96:0)
[10:34:24.875] Timer tick, asking Buildbucket for the build status
[10:34:24.942] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:24.942] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:97:0) after 1m0s
[10:35:24.962] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:97:0)
[10:35:24.962] Timer tick, asking Buildbucket for the build status
[10:35:25.052] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:25.052] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:98:0) after 1m0s
[10:36:25.107] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:98:0)
[10:36:25.107] Timer tick, asking Buildbucket for the build status
[10:36:25.177] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:25.177] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:99:0) after 1m0s
[10:37:25.195] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:99:0)
[10:37:25.195] Timer tick, asking Buildbucket for the build status
[10:37:25.259] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:25.259] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:100:0) after 1m0s
[10:38:25.286] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:100:0)
[10:38:25.286] Timer tick, asking Buildbucket for the build status
[10:38:25.379] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:25.379] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:101:0) after 1m0s
[10:39:25.507] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:101:0)
[10:39:25.507] Timer tick, asking Buildbucket for the build status
[10:39:25.573] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:25.573] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:102:0) after 1m0s
[10:40:25.635] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:102:0)
[10:40:25.635] Timer tick, asking Buildbucket for the build status
[10:40:25.717] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:25.718] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:103:0) after 1m0s
[10:41:25.813] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:103:0)
[10:41:25.813] Timer tick, asking Buildbucket for the build status
[10:41:25.931] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:25.931] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:104:0) after 1m0s
[10:42:25.930] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:104:0)
[10:42:25.930] Timer tick, asking Buildbucket for the build status
[10:42:26.008] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:26.008] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:105:0) after 1m0s
[10:43:26.027] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:105:0)
[10:43:26.027] Timer tick, asking Buildbucket for the build status
[10:43:26.136] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:26.136] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:106:0) after 1m0s
[10:44:26.454] Handling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:106:0)
[10:44:26.454] Timer tick, asking Buildbucket for the build status
[10:44:26.576] Build 8853340057489022224: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:26.576] Scheduling timer "check-buildbucket-build-status" (r8/linux-run-on-app-dump:9018770119533149712:107:0) after 1m0s
[10:45:17.367] Received PubSub notification, asking Buildbucket for the build status
[10:45:17.443] Build 8853340057489022224: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:45:17.443] Buildbucket build:
{
"build": {
"bucket": "luci.r8.ci",
"canary_preference": "PROD",
"completed_ts": "1615200317109467",
"created_by": "project:r8",
"created_ts": "1615193994970275",
"failure_reason": "BUILD_FAILURE",
"id": "8853340057489022224",
"parameters_json": "{\"builder_name\": \"linux-run-on-app-dump\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://r8.googlesource.com/r8\", \"revision\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@5e090c7c100c3200841f5859e986a29a2e0eff0b\", \"title\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\", \"url\": \"https://r8.googlesource.com/r8/+/5e090c7c100c3200841f5859e986a29a2e0eff0b\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\"}}",
"project": "r8",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Run tests') (retcode: 16)\"}, \"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\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\"}, \"id\": \"https://r8.googlesource.com/r8/+/refs/heads/master@5e090c7c100c3200841f5859e986a29a2e0eff0b\", \"title\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\", \"url\": \"https://r8.googlesource.com/r8/+/5e090c7c100c3200841f5859e986a29a2e0eff0b\"}]}, \"bot_id\": \"luci-r8-custom-ci-xenial-13-0hx5\", \"branch\": \"refs/heads/master\", \"builder_group\": \"internal.client.r8\", \"buildername\": \"linux-run-on-app-dump\", \"buildnumber\": 583, \"path_config\": \"generic\", \"recipe\": \"rex\", \"repository\": \"https://r8.googlesource.com/r8\", \"revision\": \"5e090c7c100c3200841f5859e986a29a2e0eff0b\", \"test_options\": [\"--bot\"], \"test_wrapper\": \"tools/run_on_app_dump.py\"}, \"swarming\": {\"bot_dimensions\": {\"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"r8bot-265908\"], \"gpu\": [\"none\"], \"id\": [\"luci-r8-custom-ci-xenial-13-0hx5\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"normal\": [\"true\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.r8.ci\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5744-5c0a4b4\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-a\"]}}, \"ui\": {\"info\": \"Step('Run tests') (retcode: 16)\"}}",
"service_account": "r8-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1615194012845710",
"status": "COMPLETED",
"status_changed_ts": "1615194013939840",
"tags": [
"build_address:luci.r8.ci/linux-run-on-app-dump/583",
"builder:linux-run-on-app-dump",
"buildset:commit/git/5e090c7c100c3200841f5859e986a29a2e0eff0b",
"buildset:commit/gitiles/r8.googlesource.com/r8/+/5e090c7c100c3200841f5859e986a29a2e0eff0b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018770119533149712",
"scheduler_job_id:r8/linux-run-on-app-dump",
"swarming_hostname:chrome-swarming.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/r8/buildbucket/cr-buildbucket.appspot.com/8853340057489022224/+/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:5229e2023d33f610",
"user_agent:luci-scheduler"
],
"updated_ts": "1615200317109909",
"url": "https://ci.chromium.org/b/8853340057489022224",
"utcnow_ts": "1615200317435861"
}
}
[10:45:17.443] Invocation finished in 1h45m23.806522667s with status FAILED