[15:59:40.706] New invocation is queued and will start shortly
[15:59:40.706] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[15:59:41.872] Starting the invocation (attempt 1)
[15:59:41.916] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:59:41.916] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:59:41.916] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9085513673233742576",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3721.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:9085513673233742576",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[15:59:43.186] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551542382035330",
"id": "8920083611174002832",
"lease_expiration_ts": "1551661182985626",
"lease_key": "296167955",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3721.2\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551542382986440",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085513673233742576",
"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/8920083611174002832/+/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:4357f37851107b10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551542382986538",
"url": "https://ci.chromium.org/b/8920083611174002832",
"utcnow_ts": "1551542383142875"
}
}
[15:59:43.186] Task URL: https://ci.chromium.org/b/8920083611174002832
[15:59:43.186] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:2:0) after 1m0s
[15:59:58.283] Received PubSub notification, asking Buildbucket for the build status
[15:59:58.329] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:43.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:2:0)
[16:00:43.164] Timer tick, asking Buildbucket for the build status
[16:00:43.243] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:43.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:4:0) after 1m0s
[16:01:43.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:4:0)
[16:01:43.298] Timer tick, asking Buildbucket for the build status
[16:01:43.403] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:43.403] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:5:0) after 1m0s
[16:02:43.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:5:0)
[16:02:43.419] Timer tick, asking Buildbucket for the build status
[16:02:43.524] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:43.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:6:0) after 1m0s
[16:03:43.492] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:6:0)
[16:03:43.492] Timer tick, asking Buildbucket for the build status
[16:03:43.539] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:43.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:7:0) after 1m0s
[16:04:43.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:7:0)
[16:04:43.559] Timer tick, asking Buildbucket for the build status
[16:04:43.630] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:43.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:8:0) after 1m0s
[16:05:43.619] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:8:0)
[16:05:43.619] Timer tick, asking Buildbucket for the build status
[16:05:43.808] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:43.808] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:9:0) after 1m0s
[16:06:43.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:9:0)
[16:06:43.802] Timer tick, asking Buildbucket for the build status
[16:06:43.955] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:43.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:10:0) after 1m0s
[16:07:43.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:10:0)
[16:07:43.977] Timer tick, asking Buildbucket for the build status
[16:07:44.014] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:44.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:11:0) after 1m0s
[16:08:44.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:11:0)
[16:08:44.047] Timer tick, asking Buildbucket for the build status
[16:08:44.199] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:44.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:12:0) after 1m0s
[16:09:44.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:12:0)
[16:09:44.228] Timer tick, asking Buildbucket for the build status
[16:09:44.319] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:44.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:13:0) after 1m0s
[16:10:44.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:13:0)
[16:10:44.319] Timer tick, asking Buildbucket for the build status
[16:10:44.393] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:44.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:14:0) after 1m0s
[16:11:44.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:14:0)
[16:11:44.419] Timer tick, asking Buildbucket for the build status
[16:11:44.465] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:44.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:15:0) after 1m0s
[16:12:44.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:15:0)
[16:12:44.457] Timer tick, asking Buildbucket for the build status
[16:12:44.542] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:44.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:16:0) after 1m0s
[16:13:44.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:16:0)
[16:13:44.570] Timer tick, asking Buildbucket for the build status
[16:13:44.686] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:44.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:17:0) after 1m0s
[16:14:44.703] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:17:0)
[16:14:44.703] Timer tick, asking Buildbucket for the build status
[16:14:44.764] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:44.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:18:0) after 1m0s
[16:15:44.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:18:0)
[16:15:44.740] Timer tick, asking Buildbucket for the build status
[16:15:44.968] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:44.968] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:19:0) after 1m0s
[16:16:44.987] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:19:0)
[16:16:44.987] Timer tick, asking Buildbucket for the build status
[16:16:45.173] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:45.173] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:20:0) after 1m0s
[16:17:45.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:20:0)
[16:17:45.200] Timer tick, asking Buildbucket for the build status
[16:17:45.371] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:45.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:21:0) after 1m0s
[16:18:45.390] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:21:0)
[16:18:45.390] Timer tick, asking Buildbucket for the build status
[16:18:45.560] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:45.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:22:0) after 1m0s
[16:19:45.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:22:0)
[16:19:45.569] Timer tick, asking Buildbucket for the build status
[16:19:45.778] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:45.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:23:0) after 1m0s
[16:20:45.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:23:0)
[16:20:45.750] Timer tick, asking Buildbucket for the build status
[16:20:46.059] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:46.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:24:0) after 1m0s
[16:21:46.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:24:0)
[16:21:46.129] Timer tick, asking Buildbucket for the build status
[16:21:46.381] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:46.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:25:0) after 1m0s
[16:22:46.397] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:25:0)
[16:22:46.397] Timer tick, asking Buildbucket for the build status
[16:22:46.548] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:46.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:26:0) after 1m0s
[16:23:46.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:26:0)
[16:23:46.572] Timer tick, asking Buildbucket for the build status
[16:23:46.694] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:46.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:27:0) after 1m0s
[16:24:46.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:27:0)
[16:24:46.714] Timer tick, asking Buildbucket for the build status
[16:24:46.772] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:46.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:28:0) after 1m0s
[16:25:46.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:28:0)
[16:25:46.864] Timer tick, asking Buildbucket for the build status
[16:25:47.113] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:47.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:29:0) after 1m0s
[16:26:47.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:29:0)
[16:26:47.136] Timer tick, asking Buildbucket for the build status
[16:26:47.195] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:47.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:30:0) after 1m0s
[16:27:47.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:30:0)
[16:27:47.176] Timer tick, asking Buildbucket for the build status
[16:27:47.250] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:47.251] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:31:0) after 1m0s
[16:28:47.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:31:0)
[16:28:47.232] Timer tick, asking Buildbucket for the build status
[16:28:47.426] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:47.426] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:32:0) after 1m0s
[16:29:47.450] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:32:0)
[16:29:47.450] Timer tick, asking Buildbucket for the build status
[16:29:47.512] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:47.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:33:0) after 1m0s
[16:30:47.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:33:0)
[16:30:47.540] Timer tick, asking Buildbucket for the build status
[16:30:47.598] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:47.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:34:0) after 1m0s
[16:31:47.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:34:0)
[16:31:47.616] Timer tick, asking Buildbucket for the build status
[16:31:47.797] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:47.797] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:35:0) after 1m0s
[16:32:47.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:35:0)
[16:32:47.789] Timer tick, asking Buildbucket for the build status
[16:32:47.849] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:47.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:36:0) after 1m0s
[16:33:47.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:36:0)
[16:33:47.844] Timer tick, asking Buildbucket for the build status
[16:33:47.885] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:47.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:37:0) after 1m0s
[16:34:47.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:37:0)
[16:34:47.908] Timer tick, asking Buildbucket for the build status
[16:34:47.948] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:47.948] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:38:0) after 1m0s
[16:35:47.966] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:38:0)
[16:35:47.966] Timer tick, asking Buildbucket for the build status
[16:35:48.022] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:48.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:39:0) after 1m0s
[16:36:48.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:39:0)
[16:36:48.047] Timer tick, asking Buildbucket for the build status
[16:36:48.112] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:48.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:40:0) after 1m0s
[16:37:48.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:40:0)
[16:37:48.187] Timer tick, asking Buildbucket for the build status
[16:37:48.308] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:48.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:41:0) after 1m0s
[16:38:48.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:41:0)
[16:38:48.329] Timer tick, asking Buildbucket for the build status
[16:38:48.386] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:48.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:42:0) after 1m0s
[16:39:48.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:42:0)
[16:39:48.407] Timer tick, asking Buildbucket for the build status
[16:39:48.524] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:48.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:43:0) after 1m0s
[16:40:48.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:43:0)
[16:40:48.529] Timer tick, asking Buildbucket for the build status
[16:40:48.617] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:48.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:44:0) after 1m0s
[16:41:48.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:44:0)
[16:41:48.653] Timer tick, asking Buildbucket for the build status
[16:41:48.821] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:48.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:45:0) after 1m0s
[16:42:48.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:45:0)
[16:42:48.846] Timer tick, asking Buildbucket for the build status
[16:42:48.972] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:48.972] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:46:0) after 1m0s
[16:43:48.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:46:0)
[16:43:48.992] Timer tick, asking Buildbucket for the build status
[16:43:49.101] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:49.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:47:0) after 1m0s
[16:44:49.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:47:0)
[16:44:49.105] Timer tick, asking Buildbucket for the build status
[16:44:49.203] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:49.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:48:0) after 1m0s
[16:45:49.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:48:0)
[16:45:49.208] Timer tick, asking Buildbucket for the build status
[16:45:49.392] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:49.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:49:0) after 1m0s
[16:46:49.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:49:0)
[16:46:49.383] Timer tick, asking Buildbucket for the build status
[16:46:49.589] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:49.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:50:0) after 1m0s
[16:47:49.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:50:0)
[16:47:49.596] Timer tick, asking Buildbucket for the build status
[16:47:49.661] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:49.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:51:0) after 1m0s
[16:48:49.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:51:0)
[16:48:49.673] Timer tick, asking Buildbucket for the build status
[16:48:49.819] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:49.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:52:0) after 1m0s
[16:49:49.797] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:52:0)
[16:49:49.797] Timer tick, asking Buildbucket for the build status
[16:49:50.073] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:50.073] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:53:0) after 1m0s
[16:50:50.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:53:0)
[16:50:50.091] Timer tick, asking Buildbucket for the build status
[16:50:50.159] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:50.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:54:0) after 1m0s
[16:51:50.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:54:0)
[16:51:50.177] Timer tick, asking Buildbucket for the build status
[16:51:50.222] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:50.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:55:0) after 1m0s
[16:52:50.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:55:0)
[16:52:50.245] Timer tick, asking Buildbucket for the build status
[16:52:50.306] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:50.306] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:56:0) after 1m0s
[16:53:50.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:56:0)
[16:53:50.296] Timer tick, asking Buildbucket for the build status
[16:53:50.415] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:50.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:57:0) after 1m0s
[16:54:50.448] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:57:0)
[16:54:50.448] Timer tick, asking Buildbucket for the build status
[16:54:50.511] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:50.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:58:0) after 1m0s
[16:55:50.490] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:58:0)
[16:55:50.490] Timer tick, asking Buildbucket for the build status
[16:55:50.548] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:50.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:59:0) after 1m0s
[16:56:50.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:59:0)
[16:56:50.569] Timer tick, asking Buildbucket for the build status
[16:56:50.669] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:50.669] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:60:0) after 1m0s
[16:57:50.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:60:0)
[16:57:50.656] Timer tick, asking Buildbucket for the build status
[16:57:50.715] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:50.715] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:61:0) after 1m0s
[16:58:50.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:61:0)
[16:58:50.695] Timer tick, asking Buildbucket for the build status
[16:58:50.756] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:50.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:62:0) after 1m0s
[16:59:50.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:62:0)
[16:59:50.758] Timer tick, asking Buildbucket for the build status
[16:59:50.893] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:50.893] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:63:0) after 1m0s
[17:00:51.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:63:0)
[17:00:51.001] Timer tick, asking Buildbucket for the build status
[17:00:51.072] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:51.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:64:0) after 1m0s
[17:01:51.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:64:0)
[17:01:51.091] Timer tick, asking Buildbucket for the build status
[17:01:51.130] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:51.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:65:0) after 1m0s
[17:02:51.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:65:0)
[17:02:51.188] Timer tick, asking Buildbucket for the build status
[17:02:51.276] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:51.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:66:0) after 1m0s
[17:03:51.286] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:66:0)
[17:03:51.286] Timer tick, asking Buildbucket for the build status
[17:03:51.345] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:51.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:67:0) after 1m0s
[17:04:51.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:67:0)
[17:04:51.365] Timer tick, asking Buildbucket for the build status
[17:04:51.585] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:51.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:68:0) after 1m0s
[17:05:51.604] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:68:0)
[17:05:51.604] Timer tick, asking Buildbucket for the build status
[17:05:51.663] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:51.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:69:0) after 1m0s
[17:06:51.686] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:69:0)
[17:06:51.686] Timer tick, asking Buildbucket for the build status
[17:06:51.742] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:51.742] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:70:0) after 1m0s
[17:07:51.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:70:0)
[17:07:51.766] Timer tick, asking Buildbucket for the build status
[17:07:51.882] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:51.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:71:0) after 1m0s
[17:08:51.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:71:0)
[17:08:51.873] Timer tick, asking Buildbucket for the build status
[17:08:51.937] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:51.937] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:72:0) after 1m0s
[17:09:51.976] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:72:0)
[17:09:51.976] Timer tick, asking Buildbucket for the build status
[17:09:52.098] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:52.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:73:0) after 1m0s
[17:10:52.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:73:0)
[17:10:52.125] Timer tick, asking Buildbucket for the build status
[17:10:52.241] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:52.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:74:0) after 1m0s
[17:11:52.233] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:74:0)
[17:11:52.233] Timer tick, asking Buildbucket for the build status
[17:11:52.313] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:52.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:75:0) after 1m0s
[17:12:52.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:75:0)
[17:12:52.409] Timer tick, asking Buildbucket for the build status
[17:12:52.506] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:52.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:76:0) after 1m0s
[17:13:52.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:76:0)
[17:13:52.528] Timer tick, asking Buildbucket for the build status
[17:13:52.770] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:52.770] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:77:0) after 1m0s
[17:14:52.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:77:0)
[17:14:52.794] Timer tick, asking Buildbucket for the build status
[17:14:52.900] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:52.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:78:0) after 1m0s
[17:15:52.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:78:0)
[17:15:52.896] Timer tick, asking Buildbucket for the build status
[17:15:52.962] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:52.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:79:0) after 1m0s
[17:16:52.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:79:0)
[17:16:52.989] Timer tick, asking Buildbucket for the build status
[17:16:53.046] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:53.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:80:0) after 1m0s
[17:17:53.103] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:80:0)
[17:17:53.103] Timer tick, asking Buildbucket for the build status
[17:17:53.176] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:53.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:81:0) after 1m0s
[17:18:53.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:81:0)
[17:18:53.199] Timer tick, asking Buildbucket for the build status
[17:18:53.462] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:53.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:82:0) after 1m0s
[17:19:53.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:82:0)
[17:19:53.483] Timer tick, asking Buildbucket for the build status
[17:19:53.780] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:53.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:83:0) after 1m0s
[17:20:53.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:83:0)
[17:20:53.800] Timer tick, asking Buildbucket for the build status
[17:20:53.855] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:53.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:84:0) after 1m0s
[17:21:53.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:84:0)
[17:21:53.879] Timer tick, asking Buildbucket for the build status
[17:21:53.926] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:53.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:85:0) after 1m0s
[17:22:53.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:85:0)
[17:22:53.947] Timer tick, asking Buildbucket for the build status
[17:22:54.007] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:54.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:86:0) after 1m0s
[17:23:54.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:86:0)
[17:23:54.001] Timer tick, asking Buildbucket for the build status
[17:23:54.061] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:54.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:87:0) after 1m0s
[17:24:54.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:87:0)
[17:24:54.045] Timer tick, asking Buildbucket for the build status
[17:24:54.121] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:54.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:88:0) after 1m0s
[17:25:54.143] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:88:0)
[17:25:54.143] Timer tick, asking Buildbucket for the build status
[17:25:54.204] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:54.204] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:89:0) after 1m0s
[17:26:54.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:89:0)
[17:26:54.176] Timer tick, asking Buildbucket for the build status
[17:26:54.236] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:54.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:90:0) after 1m0s
[17:27:54.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:90:0)
[17:27:54.246] Timer tick, asking Buildbucket for the build status
[17:27:54.326] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:54.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:91:0) after 1m0s
[17:28:54.345] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:91:0)
[17:28:54.345] Timer tick, asking Buildbucket for the build status
[17:28:54.709] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:54.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:92:0) after 1m0s
[17:29:54.738] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:92:0)
[17:29:54.738] Timer tick, asking Buildbucket for the build status
[17:29:54.801] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:54.801] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:93:0) after 1m0s
[17:30:54.824] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:93:0)
[17:30:54.824] Timer tick, asking Buildbucket for the build status
[17:30:54.859] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:54.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:94:0) after 1m0s
[17:31:54.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:94:0)
[17:31:54.951] Timer tick, asking Buildbucket for the build status
[17:31:55.003] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:55.003] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:95:0) after 1m0s
[17:32:55.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:95:0)
[17:32:55.035] Timer tick, asking Buildbucket for the build status
[17:32:55.192] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:55.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:96:0) after 1m0s
[17:33:55.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:96:0)
[17:33:55.211] Timer tick, asking Buildbucket for the build status
[17:33:55.280] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:55.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:97:0) after 1m0s
[17:34:55.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:97:0)
[17:34:55.299] Timer tick, asking Buildbucket for the build status
[17:34:55.479] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:55.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:98:0) after 1m0s
[17:35:55.478] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:98:0)
[17:35:55.478] Timer tick, asking Buildbucket for the build status
[17:35:55.757] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:55.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:99:0) after 1m0s
[17:36:55.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:99:0)
[17:36:55.772] Timer tick, asking Buildbucket for the build status
[17:36:55.892] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:55.892] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:100:0) after 1m0s
[17:37:55.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:100:0)
[17:37:55.915] Timer tick, asking Buildbucket for the build status
[17:37:55.991] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:55.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:101:0) after 1m0s
[17:38:56.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:101:0)
[17:38:56.077] Timer tick, asking Buildbucket for the build status
[17:38:56.138] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:56.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:102:0) after 1m0s
[17:39:56.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:102:0)
[17:39:56.165] Timer tick, asking Buildbucket for the build status
[17:39:56.305] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:56.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:103:0) after 1m0s
[17:40:56.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:103:0)
[17:40:56.327] Timer tick, asking Buildbucket for the build status
[17:40:56.419] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:56.419] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:104:0) after 1m0s
[17:41:56.439] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:104:0)
[17:41:56.439] Timer tick, asking Buildbucket for the build status
[17:41:56.513] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:56.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:105:0) after 1m0s
[17:42:56.499] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:105:0)
[17:42:56.499] Timer tick, asking Buildbucket for the build status
[17:42:56.686] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:56.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:106:0) after 1m0s
[17:43:56.694] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:106:0)
[17:43:56.694] Timer tick, asking Buildbucket for the build status
[17:43:56.819] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:56.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:107:0) after 1m0s
[17:44:56.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:107:0)
[17:44:56.841] Timer tick, asking Buildbucket for the build status
[17:44:56.909] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:56.909] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:108:0) after 1m0s
[17:45:56.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:108:0)
[17:45:56.932] Timer tick, asking Buildbucket for the build status
[17:45:57.187] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:57.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:109:0) after 1m0s
[17:46:57.195] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:109:0)
[17:46:57.195] Timer tick, asking Buildbucket for the build status
[17:46:57.665] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:57.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:110:0) after 1m0s
[17:47:57.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:110:0)
[17:47:57.671] Timer tick, asking Buildbucket for the build status
[17:47:57.889] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:57.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:111:0) after 1m0s
[17:48:57.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:111:0)
[17:48:57.911] Timer tick, asking Buildbucket for the build status
[17:48:58.076] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:58.076] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:112:0) after 1m0s
[17:49:58.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:112:0)
[17:49:58.070] Timer tick, asking Buildbucket for the build status
[17:49:58.142] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:58.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:113:0) after 1m0s
[17:50:58.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:113:0)
[17:50:58.164] Timer tick, asking Buildbucket for the build status
[17:50:58.467] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:58.467] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:114:0) after 1m0s
[17:51:58.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:114:0)
[17:51:58.472] Timer tick, asking Buildbucket for the build status
[17:51:58.540] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:58.540] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:115:0) after 1m0s
[17:52:58.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:115:0)
[17:52:58.543] Timer tick, asking Buildbucket for the build status
[17:52:58.646] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:58.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:116:0) after 1m0s
[17:53:58.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:116:0)
[17:53:58.646] Timer tick, asking Buildbucket for the build status
[17:53:58.774] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:58.774] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:117:0) after 1m0s
[17:54:58.776] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:117:0)
[17:54:58.776] Timer tick, asking Buildbucket for the build status
[17:54:59.279] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:59.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:118:0) after 1m0s
[17:55:59.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:118:0)
[17:55:59.299] Timer tick, asking Buildbucket for the build status
[17:55:59.581] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:59.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:119:0) after 1m0s
[17:56:59.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:119:0)
[17:56:59.597] Timer tick, asking Buildbucket for the build status
[17:56:59.791] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:59.791] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:120:0) after 1m0s
[17:57:59.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:120:0)
[17:57:59.828] Timer tick, asking Buildbucket for the build status
[17:57:59.994] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:59.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:121:0) after 1m0s
[17:58:59.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:121:0)
[17:58:59.983] Timer tick, asking Buildbucket for the build status
[17:59:00.255] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:00.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:122:0) after 1m0s
[18:00:00.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:122:0)
[18:00:00.278] Timer tick, asking Buildbucket for the build status
[18:00:00.345] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:00.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:123:0) after 1m0s
[18:01:00.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:123:0)
[18:01:00.327] Timer tick, asking Buildbucket for the build status
[18:01:00.586] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:00.586] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:124:0) after 1m0s
[18:02:00.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:124:0)
[18:02:00.585] Timer tick, asking Buildbucket for the build status
[18:02:00.631] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:00.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:125:0) after 1m0s
[18:03:00.610] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:125:0)
[18:03:00.610] Timer tick, asking Buildbucket for the build status
[18:03:00.777] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:00.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:126:0) after 1m0s
[18:04:00.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:126:0)
[18:04:00.763] Timer tick, asking Buildbucket for the build status
[18:04:01.805] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:01.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:127:0) after 1m0s
[18:05:01.827] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:127:0)
[18:05:01.827] Timer tick, asking Buildbucket for the build status
[18:05:02.176] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:02.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:128:0) after 1m0s
[18:06:02.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:128:0)
[18:06:02.218] Timer tick, asking Buildbucket for the build status
[18:06:02.598] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:02.598] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:129:0) after 1m0s
[18:07:02.586] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:129:0)
[18:07:02.586] Timer tick, asking Buildbucket for the build status
[18:07:02.639] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:02.639] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:130:0) after 1m0s
[18:08:02.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:130:0)
[18:08:02.648] Timer tick, asking Buildbucket for the build status
[18:08:02.889] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:02.889] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:131:0) after 1m0s
[18:09:02.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:131:0)
[18:09:02.891] Timer tick, asking Buildbucket for the build status
[18:09:03.082] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:03.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:132:0) after 1m0s
[18:10:03.058] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:132:0)
[18:10:03.058] Timer tick, asking Buildbucket for the build status
[18:10:03.272] Build 8920083611174002832: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:03.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085513673233742576:133:0) after 1m0s
[18:10:37.652] Received PubSub notification, asking Buildbucket for the build status
[18:10:37.692] Build 8920083611174002832: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:10:37.692] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1551550236863325",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551542382035330",
"id": "8920083611174002832",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3721.2\"}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"swarming\": {\"bot_dimensions\": {\"gce\": [\"1\"], \"machine_type\": [\"n1-standard-32\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"id\": [\"gce-trusty-32-0258d129-us-east1-b-8v8p\"], \"server_version\": [\"4143-92e279d\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"32\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"ui\": {\"info\": \"\"}, \"properties\": {\"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"buildername\": \"Build From Tarball\", \"recipe\": \"build_from_tarball\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1551542382035330, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9085513673233742576\", \"scheduler_job_id:infra/Build From Tarball\", \"user_agent:luci-scheduler\", \"user_agent:recipe\"], \"bucket\": \"luci.infra.cron\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"infra\", \"id\": \"8920083611174002832\"}}, \"version\": \"74.0.3721.2\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9085513673233742576\", \"key\": \"scheduler_invocation_id\"}, {\"value\": \"infra/Build From Tarball\", \"key\": \"scheduler_job_id\"}, {\"value\": \"luci-scheduler\", \"key\": \"user_agent\"}, {\"value\": \"recipe\", \"key\": \"user_agent\"}], \"builder\": {\"project\": \"infra\", \"builder\": \"Build From Tarball\", \"bucket\": \"cron\"}, \"infra\": {\"buildbucket\": {\"serviceConfigRevision\": \"ca0db2d762966d4b32d49d86567d9d7697438caf\"}, \"recipe\": {\"name\": \"build_from_tarball\", \"cipdPackage\": \"infra/recipe_bundles/chromium.googlesource.com/infra/infra\"}, \"swarming\": {\"hostname\": \"chromium-swarm.appspot.com\"}}, \"createdBy\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"id\": \"8920083611174002832\", \"input\": {}, \"createTime\": \"2019-03-02T15:59:42.035330Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-east1-b-8v8p\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551542397775057",
"status": "COMPLETED",
"status_changed_ts": "1551550237388471",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085513673233742576",
"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/8920083611174002832/+/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:4357f37851107b10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551550237389489",
"url": "https://ci.chromium.org/b/8920083611174002832",
"utcnow_ts": "1551550237684313"
}
}
[18:10:37.692] Invocation finished in 2h10m57.00572485s with status SUCCEEDED