[16:58:47.085] New invocation is queued and will start shortly
[16:58:47.085] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[16:58:48.164] Starting the invocation (attempt 1)
[16:58:48.218] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:58:48.218] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:58:48.218] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9031695356544787136",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"88.0.4294.2\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"53c6bc4e-6e10-4739-9ce2-f51156abc227\",\"title\":\"publish_tarball/0\"}]},\"version\":\"88.0.4294.2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9031695356544787136",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[16:58:48.893] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1602867528384780",
"id": "8866265294515904928",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4294.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"53c6bc4e-6e10-4739-9ce2-f51156abc227\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4294.2\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1602867528635101",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9031695356544787136",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8866265294515904928/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1602867528635229",
"url": "https://ci.chromium.org/b/8866265294515904928",
"utcnow_ts": "1602867528884511"
}
}
[16:58:48.893] Task URL: https://ci.chromium.org/b/8866265294515904928
[16:58:48.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:2:0) after 1m0s
[16:59:48.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:2:0)
[16:59:48.912] Timer tick, asking Buildbucket for the build status
[16:59:49.698] Build 8866265294515904928: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[16:59:49.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:3:0) after 1m0s
[16:59:49.844] Received PubSub notification, asking Buildbucket for the build status
[16:59:50.473] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:49.775] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:3:0)
[17:00:49.775] Timer tick, asking Buildbucket for the build status
[17:00:49.916] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:49.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:5:0) after 1m0s
[17:01:50.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:5:0)
[17:01:50.063] Timer tick, asking Buildbucket for the build status
[17:01:50.218] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:50.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:6:0) after 1m0s
[17:02:50.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:6:0)
[17:02:50.366] Timer tick, asking Buildbucket for the build status
[17:02:50.489] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:50.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:7:0) after 1m0s
[17:03:50.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:7:0)
[17:03:50.578] Timer tick, asking Buildbucket for the build status
[17:03:50.647] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:50.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:8:0) after 1m0s
[17:04:50.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:8:0)
[17:04:50.684] Timer tick, asking Buildbucket for the build status
[17:04:51.389] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:51.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:9:0) after 1m0s
[17:05:51.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:9:0)
[17:05:51.410] Timer tick, asking Buildbucket for the build status
[17:05:51.920] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:51.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:10:0) after 1m0s
[17:06:51.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:10:0)
[17:06:51.943] Timer tick, asking Buildbucket for the build status
[17:06:52.039] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:52.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:11:0) after 1m0s
[17:07:52.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:11:0)
[17:07:52.068] Timer tick, asking Buildbucket for the build status
[17:07:52.244] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:52.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:12:0) after 1m0s
[17:08:52.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:12:0)
[17:08:52.296] Timer tick, asking Buildbucket for the build status
[17:08:53.591] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:53.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:13:0) after 1m0s
[17:09:53.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:13:0)
[17:09:53.612] Timer tick, asking Buildbucket for the build status
[17:09:53.826] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:53.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:14:0) after 1m0s
[17:10:53.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:14:0)
[17:10:53.915] Timer tick, asking Buildbucket for the build status
[17:10:53.976] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:53.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:15:0) after 1m0s
[17:11:53.991] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:15:0)
[17:11:53.991] Timer tick, asking Buildbucket for the build status
[17:11:54.194] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:54.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:16:0) after 1m0s
[17:12:55.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:16:0)
[17:12:55.419] Timer tick, asking Buildbucket for the build status
[17:12:55.497] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:55.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:17:0) after 1m0s
[17:13:55.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:17:0)
[17:13:55.628] Timer tick, asking Buildbucket for the build status
[17:13:55.741] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:55.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:18:0) after 1m0s
[17:14:55.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:18:0)
[17:14:55.992] Timer tick, asking Buildbucket for the build status
[17:14:56.059] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:56.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:19:0) after 1m0s
[17:15:56.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:19:0)
[17:15:56.248] Timer tick, asking Buildbucket for the build status
[17:15:56.418] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:56.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:20:0) after 1m0s
[17:16:56.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:20:0)
[17:16:56.455] Timer tick, asking Buildbucket for the build status
[17:16:56.705] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:56.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:21:0) after 1m0s
[17:17:56.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:21:0)
[17:17:56.788] Timer tick, asking Buildbucket for the build status
[17:17:56.914] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:56.914] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:22:0) after 1m0s
[17:18:57.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:22:0)
[17:18:57.312] Timer tick, asking Buildbucket for the build status
[17:18:57.469] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:57.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:23:0) after 1m0s
[17:19:57.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:23:0)
[17:19:57.535] Timer tick, asking Buildbucket for the build status
[17:19:57.720] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:57.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:24:0) after 1m0s
[17:20:57.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:24:0)
[17:20:57.743] Timer tick, asking Buildbucket for the build status
[17:20:57.837] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:57.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:25:0) after 1m0s
[17:21:57.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:25:0)
[17:21:57.844] Timer tick, asking Buildbucket for the build status
[17:21:58.084] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:58.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:26:0) after 1m0s
[17:22:58.169] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:26:0)
[17:22:58.169] Timer tick, asking Buildbucket for the build status
[17:22:58.252] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:58.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:27:0) after 1m0s
[17:23:58.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:27:0)
[17:23:58.271] Timer tick, asking Buildbucket for the build status
[17:23:59.134] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:59.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:28:0) after 1m0s
[17:24:59.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:28:0)
[17:24:59.285] Timer tick, asking Buildbucket for the build status
[17:24:59.474] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:59.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:29:0) after 1m0s
[17:25:59.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:29:0)
[17:25:59.636] Timer tick, asking Buildbucket for the build status
[17:25:59.751] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:59.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:30:0) after 1m0s
[17:26:59.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:30:0)
[17:26:59.798] Timer tick, asking Buildbucket for the build status
[17:26:59.862] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:59.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:31:0) after 1m0s
[17:27:59.883] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:31:0)
[17:27:59.883] Timer tick, asking Buildbucket for the build status
[17:27:59.947] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:59.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:32:0) after 1m0s
[17:29:00.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:32:0)
[17:29:00.041] Timer tick, asking Buildbucket for the build status
[17:29:00.654] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:00.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:33:0) after 1m0s
[17:30:01.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:33:0)
[17:30:01.557] Timer tick, asking Buildbucket for the build status
[17:30:01.637] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:01.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:34:0) after 1m0s
[17:31:01.718] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:34:0)
[17:31:01.718] Timer tick, asking Buildbucket for the build status
[17:31:01.935] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:01.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:35:0) after 1m0s
[17:32:02.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:35:0)
[17:32:02.156] Timer tick, asking Buildbucket for the build status
[17:32:02.223] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:02.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:36:0) after 1m0s
[17:33:02.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:36:0)
[17:33:02.773] Timer tick, asking Buildbucket for the build status
[17:33:03.125] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:03.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:37:0) after 1m0s
[17:34:03.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:37:0)
[17:34:03.567] Timer tick, asking Buildbucket for the build status
[17:34:03.778] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:03.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:38:0) after 1m0s
[17:35:03.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:38:0)
[17:35:03.860] Timer tick, asking Buildbucket for the build status
[17:35:05.079] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:05.079] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:39:0) after 1m0s
[17:36:05.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:39:0)
[17:36:05.131] Timer tick, asking Buildbucket for the build status
[17:36:05.237] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:05.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:40:0) after 1m0s
[17:37:05.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:40:0)
[17:37:05.282] Timer tick, asking Buildbucket for the build status
[17:37:06.518] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:06.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:41:0) after 1m0s
[17:38:06.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:41:0)
[17:38:06.582] Timer tick, asking Buildbucket for the build status
[17:38:06.662] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:06.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:42:0) after 1m0s
[17:39:06.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:42:0)
[17:39:06.761] Timer tick, asking Buildbucket for the build status
[17:39:07.000] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:07.000] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:43:0) after 1m0s
[17:40:07.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:43:0)
[17:40:07.168] Timer tick, asking Buildbucket for the build status
[17:40:07.285] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:07.285] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:44:0) after 1m0s
[17:41:07.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:44:0)
[17:41:07.288] Timer tick, asking Buildbucket for the build status
[17:41:07.612] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:07.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:45:0) after 1m0s
[17:42:07.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:45:0)
[17:42:07.758] Timer tick, asking Buildbucket for the build status
[17:42:08.119] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:08.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:46:0) after 1m0s
[17:43:08.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:46:0)
[17:43:08.150] Timer tick, asking Buildbucket for the build status
[17:43:08.226] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:08.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:47:0) after 1m0s
[17:44:08.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:47:0)
[17:44:08.500] Timer tick, asking Buildbucket for the build status
[17:44:08.625] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:08.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:48:0) after 1m0s
[17:45:08.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:48:0)
[17:45:08.781] Timer tick, asking Buildbucket for the build status
[17:45:08.896] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:08.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:49:0) after 1m0s
[17:46:08.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:49:0)
[17:46:08.980] Timer tick, asking Buildbucket for the build status
[17:46:09.306] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:09.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:50:0) after 1m0s
[17:47:09.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:50:0)
[17:47:09.451] Timer tick, asking Buildbucket for the build status
[17:47:09.774] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:09.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:51:0) after 1m0s
[17:48:09.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:51:0)
[17:48:09.831] Timer tick, asking Buildbucket for the build status
[17:48:09.989] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:09.989] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:52:0) after 1m0s
[17:49:10.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:52:0)
[17:49:10.144] Timer tick, asking Buildbucket for the build status
[17:49:10.268] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:10.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:53:0) after 1m0s
[17:50:10.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:53:0)
[17:50:10.341] Timer tick, asking Buildbucket for the build status
[17:50:10.412] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:10.412] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:54:0) after 1m0s
[17:51:10.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:54:0)
[17:51:10.511] Timer tick, asking Buildbucket for the build status
[17:51:10.578] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:10.578] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:55:0) after 1m0s
[17:52:10.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:55:0)
[17:52:10.773] Timer tick, asking Buildbucket for the build status
[17:52:10.933] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:10.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:56:0) after 1m0s
[17:53:11.027] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:56:0)
[17:53:11.027] Timer tick, asking Buildbucket for the build status
[17:53:11.295] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:11.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:57:0) after 1m0s
[17:54:11.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:57:0)
[17:54:11.356] Timer tick, asking Buildbucket for the build status
[17:54:11.557] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:11.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:58:0) after 1m0s
[17:55:11.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:58:0)
[17:55:11.583] Timer tick, asking Buildbucket for the build status
[17:55:11.644] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:11.644] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:59:0) after 1m0s
[17:56:11.666] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:59:0)
[17:56:11.666] Timer tick, asking Buildbucket for the build status
[17:56:11.748] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:11.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:60:0) after 1m0s
[17:57:11.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:60:0)
[17:57:11.943] Timer tick, asking Buildbucket for the build status
[17:57:12.018] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:12.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:61:0) after 1m0s
[17:58:12.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:61:0)
[17:58:12.093] Timer tick, asking Buildbucket for the build status
[17:58:12.169] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:12.169] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:62:0) after 1m0s
[17:59:13.023] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:62:0)
[17:59:13.023] Timer tick, asking Buildbucket for the build status
[17:59:13.163] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:13.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:63:0) after 1m0s
[18:00:13.230] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:63:0)
[18:00:13.230] Timer tick, asking Buildbucket for the build status
[18:00:13.299] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:13.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:64:0) after 1m0s
[18:01:13.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:64:0)
[18:01:13.392] Timer tick, asking Buildbucket for the build status
[18:01:13.502] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:13.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:65:0) after 1m0s
[18:02:13.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:65:0)
[18:02:13.512] Timer tick, asking Buildbucket for the build status
[18:02:13.642] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:13.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:66:0) after 1m0s
[18:03:13.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:66:0)
[18:03:13.723] Timer tick, asking Buildbucket for the build status
[18:03:14.241] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:14.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:67:0) after 1m0s
[18:04:14.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:67:0)
[18:04:14.266] Timer tick, asking Buildbucket for the build status
[18:04:14.718] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:14.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:68:0) after 1m0s
[18:05:14.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:68:0)
[18:05:14.882] Timer tick, asking Buildbucket for the build status
[18:05:15.097] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:15.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:69:0) after 1m0s
[18:06:15.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:69:0)
[18:06:15.157] Timer tick, asking Buildbucket for the build status
[18:06:15.319] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:15.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:70:0) after 1m0s
[18:07:15.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:70:0)
[18:07:15.373] Timer tick, asking Buildbucket for the build status
[18:07:16.522] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:16.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:71:0) after 1m0s
[18:08:16.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:71:0)
[18:08:16.509] Timer tick, asking Buildbucket for the build status
[18:08:16.575] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:16.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:72:0) after 1m0s
[18:09:16.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:72:0)
[18:09:16.607] Timer tick, asking Buildbucket for the build status
[18:09:16.897] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:16.897] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:73:0) after 1m0s
[18:10:16.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:73:0)
[18:10:16.919] Timer tick, asking Buildbucket for the build status
[18:10:17.201] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:17.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:74:0) after 1m0s
[18:11:17.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:74:0)
[18:11:17.296] Timer tick, asking Buildbucket for the build status
[18:11:17.602] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:17.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:75:0) after 1m0s
[18:12:17.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:75:0)
[18:12:17.672] Timer tick, asking Buildbucket for the build status
[18:12:17.804] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:17.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:76:0) after 1m0s
[18:13:17.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:76:0)
[18:13:17.953] Timer tick, asking Buildbucket for the build status
[18:13:28.687] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:28.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:77:0) after 1m0s
[18:14:28.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:77:0)
[18:14:28.763] Timer tick, asking Buildbucket for the build status
[18:14:29.047] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:29.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:78:0) after 1m0s
[18:15:29.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:78:0)
[18:15:29.155] Timer tick, asking Buildbucket for the build status
[18:15:29.243] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:29.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:79:0) after 1m0s
[18:16:29.266] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:79:0)
[18:16:29.266] Timer tick, asking Buildbucket for the build status
[18:16:29.350] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:29.350] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:80:0) after 1m0s
[18:17:29.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:80:0)
[18:17:29.423] Timer tick, asking Buildbucket for the build status
[18:17:29.495] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:29.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:81:0) after 1m0s
[18:18:29.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:81:0)
[18:18:29.495] Timer tick, asking Buildbucket for the build status
[18:18:29.562] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:29.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:82:0) after 1m0s
[18:19:29.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:82:0)
[18:19:29.635] Timer tick, asking Buildbucket for the build status
[18:19:29.775] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:29.775] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:83:0) after 1m0s
[18:20:29.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:83:0)
[18:20:29.885] Timer tick, asking Buildbucket for the build status
[18:20:30.054] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:30.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:84:0) after 1m0s
[18:21:30.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:84:0)
[18:21:30.084] Timer tick, asking Buildbucket for the build status
[18:21:30.296] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:30.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:85:0) after 1m0s
[18:22:30.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:85:0)
[18:22:30.485] Timer tick, asking Buildbucket for the build status
[18:22:30.611] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:30.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:86:0) after 1m0s
[18:23:30.783] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:86:0)
[18:23:30.783] Timer tick, asking Buildbucket for the build status
[18:23:30.866] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:30.866] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:87:0) after 1m0s
[18:24:31.062] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:87:0)
[18:24:31.062] Timer tick, asking Buildbucket for the build status
[18:24:31.380] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:31.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:88:0) after 1m0s
[18:25:31.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:88:0)
[18:25:31.380] Timer tick, asking Buildbucket for the build status
[18:25:31.527] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:31.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:89:0) after 1m0s
[18:26:31.618] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:89:0)
[18:26:31.618] Timer tick, asking Buildbucket for the build status
[18:26:31.737] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:31.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:90:0) after 1m0s
[18:27:31.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:90:0)
[18:27:31.760] Timer tick, asking Buildbucket for the build status
[18:27:31.839] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:31.839] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:91:0) after 1m0s
[18:28:31.835] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:91:0)
[18:28:31.835] Timer tick, asking Buildbucket for the build status
[18:28:31.927] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:31.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:92:0) after 1m0s
[18:29:31.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:92:0)
[18:29:31.951] Timer tick, asking Buildbucket for the build status
[18:29:32.237] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:32.237] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:93:0) after 1m0s
[18:30:32.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:93:0)
[18:30:32.245] Timer tick, asking Buildbucket for the build status
[18:30:32.336] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:32.336] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:94:0) after 1m0s
[18:31:32.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:94:0)
[18:31:32.478] Timer tick, asking Buildbucket for the build status
[18:31:32.554] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:32.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:95:0) after 1m0s
[18:32:32.583] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:95:0)
[18:32:32.583] Timer tick, asking Buildbucket for the build status
[18:32:32.677] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:32.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:96:0) after 1m0s
[18:33:32.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:96:0)
[18:33:32.711] Timer tick, asking Buildbucket for the build status
[18:33:32.869] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:32.869] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:97:0) after 1m0s
[18:34:32.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:97:0)
[18:34:32.894] Timer tick, asking Buildbucket for the build status
[18:34:33.036] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:33.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:98:0) after 1m0s
[18:35:33.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:98:0)
[18:35:33.102] Timer tick, asking Buildbucket for the build status
[18:35:33.161] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:33.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:99:0) after 1m0s
[18:36:33.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:99:0)
[18:36:33.216] Timer tick, asking Buildbucket for the build status
[18:36:33.709] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:33.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:100:0) after 1m0s
[18:37:33.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:100:0)
[18:37:33.838] Timer tick, asking Buildbucket for the build status
[18:37:33.938] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:33.938] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:101:0) after 1m0s
[18:38:34.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:101:0)
[18:38:34.073] Timer tick, asking Buildbucket for the build status
[18:38:35.210] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:35.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:102:0) after 1m0s
[18:39:35.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:102:0)
[18:39:35.312] Timer tick, asking Buildbucket for the build status
[18:39:36.464] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:36.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:103:0) after 1m0s
[18:40:37.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:103:0)
[18:40:37.034] Timer tick, asking Buildbucket for the build status
[18:40:37.145] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:37.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:104:0) after 1m0s
[18:41:37.220] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:104:0)
[18:41:37.220] Timer tick, asking Buildbucket for the build status
[18:41:37.318] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:37.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:105:0) after 1m0s
[18:42:37.405] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:105:0)
[18:42:37.405] Timer tick, asking Buildbucket for the build status
[18:42:37.474] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:37.475] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:106:0) after 1m0s
[18:43:37.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:106:0)
[18:43:37.493] Timer tick, asking Buildbucket for the build status
[18:43:37.706] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:37.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:107:0) after 1m0s
[18:44:37.764] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:107:0)
[18:44:37.764] Timer tick, asking Buildbucket for the build status
[18:44:37.903] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:37.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:108:0) after 1m0s
[18:45:37.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:108:0)
[18:45:37.893] Timer tick, asking Buildbucket for the build status
[18:45:38.173] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:38.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:109:0) after 1m0s
[18:46:38.224] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:109:0)
[18:46:38.224] Timer tick, asking Buildbucket for the build status
[18:46:38.288] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:38.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:110:0) after 1m0s
[18:47:38.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:110:0)
[18:47:38.296] Timer tick, asking Buildbucket for the build status
[18:47:38.433] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:38.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:111:0) after 1m0s
[18:48:38.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:111:0)
[18:48:38.515] Timer tick, asking Buildbucket for the build status
[18:48:38.612] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:38.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:112:0) after 1m0s
[18:49:38.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:112:0)
[18:49:38.604] Timer tick, asking Buildbucket for the build status
[18:49:38.718] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:38.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:113:0) after 1m0s
[18:50:38.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:113:0)
[18:50:38.789] Timer tick, asking Buildbucket for the build status
[18:50:38.998] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:38.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:114:0) after 1m0s
[18:51:39.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:114:0)
[18:51:39.095] Timer tick, asking Buildbucket for the build status
[18:51:39.287] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:39.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:115:0) after 1m0s
[18:52:39.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:115:0)
[18:52:39.386] Timer tick, asking Buildbucket for the build status
[18:52:39.724] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:39.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:116:0) after 1m0s
[18:53:39.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:116:0)
[18:53:39.815] Timer tick, asking Buildbucket for the build status
[18:53:39.929] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:39.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:117:0) after 1m0s
[18:54:39.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:117:0)
[18:54:39.950] Timer tick, asking Buildbucket for the build status
[18:54:40.371] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:40.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:118:0) after 1m0s
[18:55:40.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:118:0)
[18:55:40.561] Timer tick, asking Buildbucket for the build status
[18:55:41.273] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:41.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:119:0) after 1m0s
[18:56:41.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:119:0)
[18:56:41.341] Timer tick, asking Buildbucket for the build status
[18:56:41.455] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:41.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:120:0) after 1m0s
[18:57:41.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:120:0)
[18:57:41.477] Timer tick, asking Buildbucket for the build status
[18:57:41.567] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:41.567] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:121:0) after 1m0s
[18:58:41.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:121:0)
[18:58:41.597] Timer tick, asking Buildbucket for the build status
[18:58:41.677] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:41.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:122:0) after 1m0s
[18:59:41.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:122:0)
[18:59:41.752] Timer tick, asking Buildbucket for the build status
[18:59:42.597] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:42.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:123:0) after 1m0s
[19:00:42.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:123:0)
[19:00:42.603] Timer tick, asking Buildbucket for the build status
[19:00:42.716] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:42.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:124:0) after 1m0s
[19:01:42.790] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:124:0)
[19:01:42.790] Timer tick, asking Buildbucket for the build status
[19:01:42.903] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:42.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:125:0) after 1m0s
[19:02:42.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:125:0)
[19:02:42.975] Timer tick, asking Buildbucket for the build status
[19:02:43.241] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:43.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:126:0) after 1m0s
[19:03:43.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:126:0)
[19:03:43.296] Timer tick, asking Buildbucket for the build status
[19:03:43.380] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:43.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:127:0) after 1m0s
[19:04:43.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:127:0)
[19:04:43.475] Timer tick, asking Buildbucket for the build status
[19:04:43.602] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:43.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:128:0) after 1m0s
[19:05:43.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:128:0)
[19:05:43.714] Timer tick, asking Buildbucket for the build status
[19:05:43.825] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:43.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:129:0) after 1m0s
[19:06:43.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:129:0)
[19:06:43.877] Timer tick, asking Buildbucket for the build status
[19:06:44.314] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:44.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:130:0) after 1m0s
[19:07:44.388] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:130:0)
[19:07:44.388] Timer tick, asking Buildbucket for the build status
[19:07:44.496] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:44.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:131:0) after 1m0s
[19:08:44.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:131:0)
[19:08:44.593] Timer tick, asking Buildbucket for the build status
[19:08:44.909] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:44.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:132:0) after 1m0s
[19:09:44.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:132:0)
[19:09:44.956] Timer tick, asking Buildbucket for the build status
[19:09:45.321] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:45.321] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:133:0) after 1m0s
[19:10:45.351] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:133:0)
[19:10:45.351] Timer tick, asking Buildbucket for the build status
[19:10:45.590] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:45.590] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:134:0) after 1m0s
[19:11:45.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:134:0)
[19:11:45.629] Timer tick, asking Buildbucket for the build status
[19:11:45.702] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:45.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:135:0) after 1m0s
[19:12:45.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:135:0)
[19:12:45.810] Timer tick, asking Buildbucket for the build status
[19:12:46.217] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:46.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:136:0) after 1m0s
[19:13:46.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:136:0)
[19:13:46.337] Timer tick, asking Buildbucket for the build status
[19:13:46.674] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:46.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:137:0) after 1m0s
[19:14:46.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:137:0)
[19:14:46.701] Timer tick, asking Buildbucket for the build status
[19:14:47.218] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:47.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:138:0) after 1m0s
[19:15:47.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:138:0)
[19:15:47.333] Timer tick, asking Buildbucket for the build status
[19:15:47.428] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:47.428] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:139:0) after 1m0s
[19:16:47.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:139:0)
[19:16:47.496] Timer tick, asking Buildbucket for the build status
[19:16:47.763] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:47.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:140:0) after 1m0s
[19:17:47.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:140:0)
[19:17:47.788] Timer tick, asking Buildbucket for the build status
[19:17:47.855] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:47.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:141:0) after 1m0s
[19:18:47.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:141:0)
[19:18:47.867] Timer tick, asking Buildbucket for the build status
[19:18:48.172] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:48.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:142:0) after 1m0s
[19:19:48.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:142:0)
[19:19:48.204] Timer tick, asking Buildbucket for the build status
[19:19:48.317] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:48.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:143:0) after 1m0s
[19:20:48.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:143:0)
[19:20:48.410] Timer tick, asking Buildbucket for the build status
[19:20:48.483] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:48.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:144:0) after 1m0s
[19:21:48.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:144:0)
[19:21:48.516] Timer tick, asking Buildbucket for the build status
[19:21:48.634] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:48.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:145:0) after 1m0s
[19:22:48.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:145:0)
[19:22:48.646] Timer tick, asking Buildbucket for the build status
[19:22:48.735] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:48.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:146:0) after 1m0s
[19:23:48.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:146:0)
[19:23:48.757] Timer tick, asking Buildbucket for the build status
[19:23:48.833] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:48.833] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:147:0) after 1m0s
[19:24:48.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:147:0)
[19:24:48.867] Timer tick, asking Buildbucket for the build status
[19:24:48.973] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:48.973] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:148:0) after 1m0s
[19:25:49.002] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:148:0)
[19:25:49.002] Timer tick, asking Buildbucket for the build status
[19:25:49.066] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:49.066] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:149:0) after 1m0s
[19:26:49.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:149:0)
[19:26:49.093] Timer tick, asking Buildbucket for the build status
[19:26:49.210] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:49.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:150:0) after 1m0s
[19:27:49.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:150:0)
[19:27:49.241] Timer tick, asking Buildbucket for the build status
[19:27:49.353] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:49.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:151:0) after 1m0s
[19:28:49.452] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:151:0)
[19:28:49.452] Timer tick, asking Buildbucket for the build status
[19:28:49.522] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:49.522] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:152:0) after 1m0s
[19:29:49.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:152:0)
[19:29:49.623] Timer tick, asking Buildbucket for the build status
[19:29:49.942] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:49.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:153:0) after 1m0s
[19:30:50.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:153:0)
[19:30:50.015] Timer tick, asking Buildbucket for the build status
[19:30:50.082] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:50.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:154:0) after 1m0s
[19:31:50.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:154:0)
[19:31:50.157] Timer tick, asking Buildbucket for the build status
[19:31:50.240] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:50.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:155:0) after 1m0s
[19:32:50.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:155:0)
[19:32:50.251] Timer tick, asking Buildbucket for the build status
[19:32:50.346] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:50.346] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:156:0) after 1m0s
[19:33:50.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:156:0)
[19:33:50.410] Timer tick, asking Buildbucket for the build status
[19:33:50.482] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:50.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:157:0) after 1m0s
[19:34:50.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:157:0)
[19:34:50.565] Timer tick, asking Buildbucket for the build status
[19:34:50.663] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:50.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:158:0) after 1m0s
[19:35:51.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:158:0)
[19:35:51.543] Timer tick, asking Buildbucket for the build status
[19:35:51.820] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:51.820] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:159:0) after 1m0s
[19:36:51.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:159:0)
[19:36:51.845] Timer tick, asking Buildbucket for the build status
[19:36:52.272] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:52.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:160:0) after 1m0s
[19:37:53.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:160:0)
[19:37:53.622] Timer tick, asking Buildbucket for the build status
[19:37:53.696] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:53.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:161:0) after 1m0s
[19:38:53.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:161:0)
[19:38:53.788] Timer tick, asking Buildbucket for the build status
[19:38:53.853] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:53.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:162:0) after 1m0s
[19:39:54.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:162:0)
[19:39:54.045] Timer tick, asking Buildbucket for the build status
[19:39:54.155] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:54.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:163:0) after 1m0s
[19:40:54.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:163:0)
[19:40:54.279] Timer tick, asking Buildbucket for the build status
[19:40:55.760] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:55.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:164:0) after 1m0s
[19:41:55.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:164:0)
[19:41:55.942] Timer tick, asking Buildbucket for the build status
[19:41:56.149] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:56.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:165:0) after 1m0s
[19:42:56.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:165:0)
[19:42:56.260] Timer tick, asking Buildbucket for the build status
[19:42:56.356] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:56.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:166:0) after 1m0s
[19:43:56.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:166:0)
[19:43:56.478] Timer tick, asking Buildbucket for the build status
[19:43:56.604] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:56.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:167:0) after 1m0s
[19:44:56.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:167:0)
[19:44:56.752] Timer tick, asking Buildbucket for the build status
[19:44:56.939] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:56.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:168:0) after 1m0s
[19:45:56.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:168:0)
[19:45:56.927] Timer tick, asking Buildbucket for the build status
[19:45:57.008] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:57.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:169:0) after 1m0s
[19:46:57.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:169:0)
[19:46:57.091] Timer tick, asking Buildbucket for the build status
[19:46:57.243] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:57.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:170:0) after 1m0s
[19:47:57.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:170:0)
[19:47:57.321] Timer tick, asking Buildbucket for the build status
[19:47:57.513] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:57.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:171:0) after 1m0s
[19:48:57.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:171:0)
[19:48:57.588] Timer tick, asking Buildbucket for the build status
[19:48:57.798] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:57.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:172:0) after 1m0s
[19:49:57.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:172:0)
[19:49:57.805] Timer tick, asking Buildbucket for the build status
[19:49:57.928] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:57.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:173:0) after 1m0s
[19:50:57.962] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:173:0)
[19:50:57.962] Timer tick, asking Buildbucket for the build status
[19:50:58.130] Build 8866265294515904928: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:58.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9031695356544787136:174:0) after 1m0s
[19:51:10.202] Received PubSub notification, asking Buildbucket for the build status
[19:51:10.333] Build 8866265294515904928: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:51:10.333] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1602877869421568",
"created_by": "project:infra",
"created_ts": "1602867528384780",
"id": "8866265294515904928",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"88.0.4294.2\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"53c6bc4e-6e10-4739-9ce2-f51156abc227\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"88.0.4294.2\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-r4xs\"], \"image\": [\"chrome-xenial-20101400-bb93c5ec2c2\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"5595-820ff29\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1602867589217320",
"status": "COMPLETED",
"status_changed_ts": "1602877869737438",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9031695356544787136",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8866265294515904928/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:4f4b2b1e01fee110",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1602877869785658",
"url": "https://ci.chromium.org/b/8866265294515904928",
"utcnow_ts": "1602877870323013"
}
}
[19:51:10.333] Invocation finished in 2h52m23.267822556s with status SUCCEEDED