[14:22:26.160] New invocation is queued and will start shortly
[14:22:26.160] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[14:22:27.248] Starting the invocation (attempt 1)
[14:22:27.293] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:22:27.294] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:22:27.294] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9085610388161208528",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"74.0.3718.8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9085610388161208528",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[14:22:28.698] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551450147464266",
"id": "8920180326130661344",
"lease_expiration_ts": "1551568948126092",
"lease_key": "417556148",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3718.8\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1551450148128694",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085610388161208528",
"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/8920180326130661344/+/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:43527414267d0610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551450148129054",
"url": "https://ci.chromium.org/b/8920180326130661344",
"utcnow_ts": "1551450148689491"
}
}
[14:22:28.698] Task URL: https://ci.chromium.org/b/8920180326130661344
[14:22:28.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:2:0) after 1m0s
[14:22:29.033] Received PubSub notification, asking Buildbucket for the build status
[14:22:29.132] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:28.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:2:0)
[14:23:28.706] Timer tick, asking Buildbucket for the build status
[14:23:28.915] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:28.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:4:0) after 1m0s
[14:24:28.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:4:0)
[14:24:28.943] Timer tick, asking Buildbucket for the build status
[14:24:29.269] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:29.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:5:0) after 1m0s
[14:25:29.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:5:0)
[14:25:29.279] Timer tick, asking Buildbucket for the build status
[14:25:29.562] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:29.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:6:0) after 1m0s
[14:26:29.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:6:0)
[14:26:29.584] Timer tick, asking Buildbucket for the build status
[14:26:29.886] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:29.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:7:0) after 1m0s
[14:27:29.909] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:7:0)
[14:27:29.909] Timer tick, asking Buildbucket for the build status
[14:27:30.051] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:30.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:8:0) after 1m0s
[14:28:30.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:8:0)
[14:28:30.047] Timer tick, asking Buildbucket for the build status
[14:28:30.091] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:30.091] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:9:0) after 1m0s
[14:29:30.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:9:0)
[14:29:30.065] Timer tick, asking Buildbucket for the build status
[14:29:30.303] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:30.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:10:0) after 1m0s
[14:30:30.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:10:0)
[14:30:30.329] Timer tick, asking Buildbucket for the build status
[14:30:30.375] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:30.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:11:0) after 1m0s
[14:31:30.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:11:0)
[14:31:30.386] Timer tick, asking Buildbucket for the build status
[14:31:30.618] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:30.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:12:0) after 1m0s
[14:32:30.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:12:0)
[14:32:30.593] Timer tick, asking Buildbucket for the build status
[14:32:30.641] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:30.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:13:0) after 1m0s
[14:33:30.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:13:0)
[14:33:30.616] Timer tick, asking Buildbucket for the build status
[14:33:30.678] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:30.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:14:0) after 1m0s
[14:34:30.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:14:0)
[14:34:30.714] Timer tick, asking Buildbucket for the build status
[14:34:30.819] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:30.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:15:0) after 1m0s
[14:35:30.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:15:0)
[14:35:30.845] Timer tick, asking Buildbucket for the build status
[14:35:30.990] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:30.990] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:16:0) after 1m0s
[14:36:30.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:16:0)
[14:36:30.984] Timer tick, asking Buildbucket for the build status
[14:36:31.049] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:31.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:17:0) after 1m0s
[14:37:31.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:17:0)
[14:37:31.067] Timer tick, asking Buildbucket for the build status
[14:37:31.107] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:31.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:18:0) after 1m0s
[14:38:31.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:18:0)
[14:38:31.102] Timer tick, asking Buildbucket for the build status
[14:38:31.146] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:31.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:19:0) after 1m0s
[14:39:31.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:19:0)
[14:39:31.162] Timer tick, asking Buildbucket for the build status
[14:39:31.415] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:31.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:20:0) after 1m0s
[14:40:31.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:20:0)
[14:40:31.415] Timer tick, asking Buildbucket for the build status
[14:40:31.480] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:31.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:21:0) after 1m0s
[14:41:31.483] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:21:0)
[14:41:31.483] Timer tick, asking Buildbucket for the build status
[14:41:31.615] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:31.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:22:0) after 1m0s
[14:42:31.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:22:0)
[14:42:31.637] Timer tick, asking Buildbucket for the build status
[14:42:31.758] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:31.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:23:0) after 1m0s
[14:43:31.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:23:0)
[14:43:31.793] Timer tick, asking Buildbucket for the build status
[14:43:31.852] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:31.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:24:0) after 1m0s
[14:44:31.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:24:0)
[14:44:31.845] Timer tick, asking Buildbucket for the build status
[14:44:31.967] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:31.967] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:25:0) after 1m0s
[14:45:31.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:25:0)
[14:45:31.993] Timer tick, asking Buildbucket for the build status
[14:45:32.056] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:32.056] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:26:0) after 1m0s
[14:46:32.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:26:0)
[14:46:32.046] Timer tick, asking Buildbucket for the build status
[14:46:32.084] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:32.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:27:0) after 1m0s
[14:47:32.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:27:0)
[14:47:32.124] Timer tick, asking Buildbucket for the build status
[14:47:32.167] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:32.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:28:0) after 1m0s
[14:48:32.177] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:28:0)
[14:48:32.177] Timer tick, asking Buildbucket for the build status
[14:48:32.281] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:32.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:29:0) after 1m0s
[14:49:32.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:29:0)
[14:49:32.296] Timer tick, asking Buildbucket for the build status
[14:49:32.339] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:32.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:30:0) after 1m0s
[14:50:32.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:30:0)
[14:50:32.335] Timer tick, asking Buildbucket for the build status
[14:50:32.788] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:32.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:31:0) after 1m0s
[14:51:32.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:31:0)
[14:51:32.782] Timer tick, asking Buildbucket for the build status
[14:51:32.916] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:32.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:32:0) after 1m0s
[14:52:32.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:32:0)
[14:52:32.897] Timer tick, asking Buildbucket for the build status
[14:52:32.962] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:32.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:33:0) after 1m0s
[14:53:32.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:33:0)
[14:53:32.970] Timer tick, asking Buildbucket for the build status
[14:53:33.022] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:33.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:34:0) after 1m0s
[14:54:33.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:34:0)
[14:54:33.034] Timer tick, asking Buildbucket for the build status
[14:54:33.212] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:33.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:35:0) after 1m0s
[14:55:33.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:35:0)
[14:55:33.240] Timer tick, asking Buildbucket for the build status
[14:55:33.377] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:33.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:36:0) after 1m0s
[14:56:33.391] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:36:0)
[14:56:33.391] Timer tick, asking Buildbucket for the build status
[14:56:33.667] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:33.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:37:0) after 1m0s
[14:57:33.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:37:0)
[14:57:33.695] Timer tick, asking Buildbucket for the build status
[14:57:33.743] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:33.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:38:0) after 1m0s
[14:58:33.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:38:0)
[14:58:33.724] Timer tick, asking Buildbucket for the build status
[14:58:33.891] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:33.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:39:0) after 1m0s
[14:59:33.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:39:0)
[14:59:33.891] Timer tick, asking Buildbucket for the build status
[14:59:33.933] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:33.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:40:0) after 1m0s
[15:00:33.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:40:0)
[15:00:33.936] Timer tick, asking Buildbucket for the build status
[15:00:34.013] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:34.013] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:41:0) after 1m0s
[15:01:34.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:41:0)
[15:01:34.013] Timer tick, asking Buildbucket for the build status
[15:01:34.092] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:34.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:42:0) after 1m0s
[15:02:34.105] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:42:0)
[15:02:34.105] Timer tick, asking Buildbucket for the build status
[15:02:34.179] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:34.179] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:43:0) after 1m0s
[15:03:34.201] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:43:0)
[15:03:34.201] Timer tick, asking Buildbucket for the build status
[15:03:34.303] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:34.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:44:0) after 1m0s
[15:04:34.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:44:0)
[15:04:34.353] Timer tick, asking Buildbucket for the build status
[15:04:34.490] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:34.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:45:0) after 1m0s
[15:05:34.514] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:45:0)
[15:05:34.514] Timer tick, asking Buildbucket for the build status
[15:05:34.743] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:34.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:46:0) after 1m0s
[15:06:34.781] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:46:0)
[15:06:34.781] Timer tick, asking Buildbucket for the build status
[15:06:34.855] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:34.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:47:0) after 1m0s
[15:07:34.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:47:0)
[15:07:34.858] Timer tick, asking Buildbucket for the build status
[15:07:34.971] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:34.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:48:0) after 1m0s
[15:08:35.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:48:0)
[15:08:35.004] Timer tick, asking Buildbucket for the build status
[15:08:35.075] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:35.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:49:0) after 1m0s
[15:09:35.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:49:0)
[15:09:35.052] Timer tick, asking Buildbucket for the build status
[15:09:35.131] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:35.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:50:0) after 1m0s
[15:10:35.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:50:0)
[15:10:35.154] Timer tick, asking Buildbucket for the build status
[15:10:35.258] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:35.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:51:0) after 1m0s
[15:11:35.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:51:0)
[15:11:35.242] Timer tick, asking Buildbucket for the build status
[15:11:35.310] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:35.310] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:52:0) after 1m0s
[15:12:35.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:52:0)
[15:12:35.331] Timer tick, asking Buildbucket for the build status
[15:12:35.511] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:35.511] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:53:0) after 1m0s
[15:13:35.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:53:0)
[15:13:35.501] Timer tick, asking Buildbucket for the build status
[15:13:35.569] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:35.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:54:0) after 1m0s
[15:14:35.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:54:0)
[15:14:35.576] Timer tick, asking Buildbucket for the build status
[15:14:35.631] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:35.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:55:0) after 1m0s
[15:15:35.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:55:0)
[15:15:35.613] Timer tick, asking Buildbucket for the build status
[15:15:35.692] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:35.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:56:0) after 1m0s
[15:16:35.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:56:0)
[15:16:35.699] Timer tick, asking Buildbucket for the build status
[15:16:35.824] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:35.824] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:57:0) after 1m0s
[15:17:35.844] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:57:0)
[15:17:35.844] Timer tick, asking Buildbucket for the build status
[15:17:35.905] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:35.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:58:0) after 1m0s
[15:18:35.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:58:0)
[15:18:35.926] Timer tick, asking Buildbucket for the build status
[15:18:36.004] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:36.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:59:0) after 1m0s
[15:19:36.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:59:0)
[15:19:36.052] Timer tick, asking Buildbucket for the build status
[15:19:36.093] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:36.093] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:60:0) after 1m0s
[15:20:36.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:60:0)
[15:20:36.064] Timer tick, asking Buildbucket for the build status
[15:20:36.155] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:36.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:61:0) after 1m0s
[15:21:36.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:61:0)
[15:21:36.184] Timer tick, asking Buildbucket for the build status
[15:21:36.290] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:36.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:62:0) after 1m0s
[15:22:36.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:62:0)
[15:22:36.280] Timer tick, asking Buildbucket for the build status
[15:22:36.364] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:36.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:63:0) after 1m0s
[15:23:36.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:63:0)
[15:23:36.382] Timer tick, asking Buildbucket for the build status
[15:23:36.485] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:36.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:64:0) after 1m0s
[15:24:36.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:64:0)
[15:24:36.550] Timer tick, asking Buildbucket for the build status
[15:24:36.606] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:36.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:65:0) after 1m0s
[15:25:36.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:65:0)
[15:25:36.623] Timer tick, asking Buildbucket for the build status
[15:25:36.679] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:36.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:66:0) after 1m0s
[15:26:36.678] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:66:0)
[15:26:36.678] Timer tick, asking Buildbucket for the build status
[15:26:36.783] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:36.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:67:0) after 1m0s
[15:27:36.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:67:0)
[15:27:36.802] Timer tick, asking Buildbucket for the build status
[15:27:36.872] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:36.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:68:0) after 1m0s
[15:28:36.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:68:0)
[15:28:36.881] Timer tick, asking Buildbucket for the build status
[15:28:36.936] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:36.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:69:0) after 1m0s
[15:29:36.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:69:0)
[15:29:36.965] Timer tick, asking Buildbucket for the build status
[15:29:37.010] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:37.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:70:0) after 1m0s
[15:30:37.015] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:70:0)
[15:30:37.015] Timer tick, asking Buildbucket for the build status
[15:30:37.103] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:37.103] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:71:0) after 1m0s
[15:31:37.119] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:71:0)
[15:31:37.119] Timer tick, asking Buildbucket for the build status
[15:31:37.212] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:37.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:72:0) after 1m0s
[15:32:37.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:72:0)
[15:32:37.214] Timer tick, asking Buildbucket for the build status
[15:32:37.257] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:37.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:73:0) after 1m0s
[15:33:37.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:73:0)
[15:33:37.292] Timer tick, asking Buildbucket for the build status
[15:33:37.343] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:37.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:74:0) after 1m0s
[15:34:37.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:74:0)
[15:34:37.365] Timer tick, asking Buildbucket for the build status
[15:34:37.562] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:37.562] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:75:0) after 1m0s
[15:35:37.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:75:0)
[15:35:37.536] Timer tick, asking Buildbucket for the build status
[15:35:37.582] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:37.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:76:0) after 1m0s
[15:36:37.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:76:0)
[15:36:37.602] Timer tick, asking Buildbucket for the build status
[15:36:37.702] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:37.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:77:0) after 1m0s
[15:37:37.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:77:0)
[15:37:37.722] Timer tick, asking Buildbucket for the build status
[15:37:37.787] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:37.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:78:0) after 1m0s
[15:38:37.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:78:0)
[15:38:37.799] Timer tick, asking Buildbucket for the build status
[15:38:37.864] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:37.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:79:0) after 1m0s
[15:39:37.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:79:0)
[15:39:37.879] Timer tick, asking Buildbucket for the build status
[15:39:37.971] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:37.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:80:0) after 1m0s
[15:40:37.992] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:80:0)
[15:40:37.992] Timer tick, asking Buildbucket for the build status
[15:40:38.033] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:38.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:81:0) after 1m0s
[15:41:38.047] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:81:0)
[15:41:38.047] Timer tick, asking Buildbucket for the build status
[15:41:38.279] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:38.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:82:0) after 1m0s
[15:42:38.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:82:0)
[15:42:38.314] Timer tick, asking Buildbucket for the build status
[15:42:38.653] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:38.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:83:0) after 1m0s
[15:43:38.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:83:0)
[15:43:38.673] Timer tick, asking Buildbucket for the build status
[15:43:38.779] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:38.779] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:84:0) after 1m0s
[15:44:38.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:84:0)
[15:44:38.765] Timer tick, asking Buildbucket for the build status
[15:44:38.807] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:38.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:85:0) after 1m0s
[15:45:38.809] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:85:0)
[15:45:38.809] Timer tick, asking Buildbucket for the build status
[15:45:38.927] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:38.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:86:0) after 1m0s
[15:46:38.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:86:0)
[15:46:38.944] Timer tick, asking Buildbucket for the build status
[15:46:39.083] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:39.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:87:0) after 1m0s
[15:47:39.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:87:0)
[15:47:39.094] Timer tick, asking Buildbucket for the build status
[15:47:39.134] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:39.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:88:0) after 1m0s
[15:48:39.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:88:0)
[15:48:39.162] Timer tick, asking Buildbucket for the build status
[15:48:39.472] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:39.472] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:89:0) after 1m0s
[15:49:39.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:89:0)
[15:49:39.496] Timer tick, asking Buildbucket for the build status
[15:49:39.545] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:39.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:90:0) after 1m0s
[15:50:39.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:90:0)
[15:50:39.542] Timer tick, asking Buildbucket for the build status
[15:50:39.654] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:39.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:91:0) after 1m0s
[15:51:39.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:91:0)
[15:51:39.646] Timer tick, asking Buildbucket for the build status
[15:51:39.709] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:39.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:92:0) after 1m0s
[15:52:39.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:92:0)
[15:52:39.713] Timer tick, asking Buildbucket for the build status
[15:52:39.754] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:39.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:93:0) after 1m0s
[15:53:39.772] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:93:0)
[15:53:39.772] Timer tick, asking Buildbucket for the build status
[15:53:39.870] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:39.870] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:94:0) after 1m0s
[15:54:39.891] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:94:0)
[15:54:39.891] Timer tick, asking Buildbucket for the build status
[15:54:39.984] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:39.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:95:0) after 1m0s
[15:55:39.993] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:95:0)
[15:55:39.993] Timer tick, asking Buildbucket for the build status
[15:55:40.059] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:40.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:96:0) after 1m0s
[15:56:40.166] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:96:0)
[15:56:40.166] Timer tick, asking Buildbucket for the build status
[15:56:40.205] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:40.205] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:97:0) after 1m0s
[15:57:40.225] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:97:0)
[15:57:40.225] Timer tick, asking Buildbucket for the build status
[15:57:40.293] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:40.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:98:0) after 1m0s
[15:58:40.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:98:0)
[15:58:40.279] Timer tick, asking Buildbucket for the build status
[15:58:40.335] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:40.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:99:0) after 1m0s
[15:59:40.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:99:0)
[15:59:40.358] Timer tick, asking Buildbucket for the build status
[15:59:40.605] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:40.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:100:0) after 1m0s
[16:00:40.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:100:0)
[16:00:40.609] Timer tick, asking Buildbucket for the build status
[16:00:40.662] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:40.662] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:101:0) after 1m0s
[16:01:40.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:101:0)
[16:01:40.649] Timer tick, asking Buildbucket for the build status
[16:01:40.708] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:40.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:102:0) after 1m0s
[16:02:40.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:102:0)
[16:02:40.752] Timer tick, asking Buildbucket for the build status
[16:02:40.796] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:40.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:103:0) after 1m0s
[16:03:40.821] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:103:0)
[16:03:40.821] Timer tick, asking Buildbucket for the build status
[16:03:41.085] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:41.085] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:104:0) after 1m0s
[16:04:41.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:104:0)
[16:04:41.122] Timer tick, asking Buildbucket for the build status
[16:04:41.167] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:41.167] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:105:0) after 1m0s
[16:05:41.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:105:0)
[16:05:41.149] Timer tick, asking Buildbucket for the build status
[16:05:41.283] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:41.283] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:106:0) after 1m0s
[16:06:41.304] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:106:0)
[16:06:41.304] Timer tick, asking Buildbucket for the build status
[16:06:41.495] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:41.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:107:0) after 1m0s
[16:07:41.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:107:0)
[16:07:41.473] Timer tick, asking Buildbucket for the build status
[16:07:41.560] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:41.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:108:0) after 1m0s
[16:08:41.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:108:0)
[16:08:41.557] Timer tick, asking Buildbucket for the build status
[16:08:41.692] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:41.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:109:0) after 1m0s
[16:09:41.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:109:0)
[16:09:41.713] Timer tick, asking Buildbucket for the build status
[16:09:41.878] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:41.878] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:110:0) after 1m0s
[16:10:41.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:110:0)
[16:10:41.905] Timer tick, asking Buildbucket for the build status
[16:10:41.946] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:41.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:111:0) after 1m0s
[16:11:41.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:111:0)
[16:11:41.972] Timer tick, asking Buildbucket for the build status
[16:11:42.164] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:42.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:112:0) after 1m0s
[16:12:42.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:112:0)
[16:12:42.184] Timer tick, asking Buildbucket for the build status
[16:12:42.390] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:42.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:113:0) after 1m0s
[16:13:42.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:113:0)
[16:13:42.443] Timer tick, asking Buildbucket for the build status
[16:13:42.489] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:42.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:114:0) after 1m0s
[16:14:42.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:114:0)
[16:14:42.477] Timer tick, asking Buildbucket for the build status
[16:14:42.528] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:42.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:115:0) after 1m0s
[16:15:42.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:115:0)
[16:15:42.535] Timer tick, asking Buildbucket for the build status
[16:15:42.595] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:42.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:116:0) after 1m0s
[16:16:42.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:116:0)
[16:16:42.608] Timer tick, asking Buildbucket for the build status
[16:16:42.673] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:42.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:117:0) after 1m0s
[16:17:42.672] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:117:0)
[16:17:42.672] Timer tick, asking Buildbucket for the build status
[16:17:42.741] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:42.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:118:0) after 1m0s
[16:18:42.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:118:0)
[16:18:42.777] Timer tick, asking Buildbucket for the build status
[16:18:42.900] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:42.900] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:119:0) after 1m0s
[16:19:42.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:119:0)
[16:19:42.882] Timer tick, asking Buildbucket for the build status
[16:19:42.945] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:42.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:120:0) after 1m0s
[16:20:42.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:120:0)
[16:20:42.989] Timer tick, asking Buildbucket for the build status
[16:20:43.086] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:43.086] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:121:0) after 1m0s
[16:21:43.110] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:121:0)
[16:21:43.110] Timer tick, asking Buildbucket for the build status
[16:21:43.518] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:43.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:122:0) after 1m0s
[16:22:43.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:122:0)
[16:22:43.524] Timer tick, asking Buildbucket for the build status
[16:22:43.575] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:43.575] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:123:0) after 1m0s
[16:23:43.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:123:0)
[16:23:43.591] Timer tick, asking Buildbucket for the build status
[16:23:43.666] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:43.666] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:124:0) after 1m0s
[16:24:43.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:124:0)
[16:24:43.685] Timer tick, asking Buildbucket for the build status
[16:24:43.799] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:43.799] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:125:0) after 1m0s
[16:25:43.826] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:125:0)
[16:25:43.826] Timer tick, asking Buildbucket for the build status
[16:25:44.102] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:44.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:126:0) after 1m0s
[16:26:44.108] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:126:0)
[16:26:44.108] Timer tick, asking Buildbucket for the build status
[16:26:44.382] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:44.382] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:127:0) after 1m0s
[16:27:44.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:127:0)
[16:27:44.378] Timer tick, asking Buildbucket for the build status
[16:27:44.421] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:44.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:128:0) after 1m0s
[16:28:44.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:128:0)
[16:28:44.398] Timer tick, asking Buildbucket for the build status
[16:28:44.481] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:44.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:129:0) after 1m0s
[16:29:44.502] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:129:0)
[16:29:44.502] Timer tick, asking Buildbucket for the build status
[16:29:44.543] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:44.543] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:130:0) after 1m0s
[16:30:44.546] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:130:0)
[16:30:44.546] Timer tick, asking Buildbucket for the build status
[16:30:44.749] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:44.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:131:0) after 1m0s
[16:31:44.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:131:0)
[16:31:44.767] Timer tick, asking Buildbucket for the build status
[16:31:44.853] Build 8920180326130661344: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:44.853] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9085610388161208528:132:0) after 1m0s
[16:32:40.647] Received PubSub notification, asking Buildbucket for the build status
[16:32:40.691] Build 8920180326130661344: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:32:40.691] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1551457959463971",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1551450147464266",
"id": "8920180326130661344",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"74.0.3718.8\"}}",
"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-bs08\"], \"server_version\": [\"4139-ace6083\"], \"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\": 1551450147464266, \"tags\": [\"builder:Build From Tarball\", \"parent_buildername:publish_tarball\", \"scheduler_invocation_id:9085610388161208528\", \"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\": \"8920180326130661344\"}}, \"version\": \"74.0.3718.8\", \"$recipe_engine/buildbucket\": {\"build\": {\"tags\": [{\"value\": \"Build From Tarball\", \"key\": \"builder\"}, {\"value\": \"publish_tarball\", \"key\": \"parent_buildername\"}, {\"value\": \"9085610388161208528\", \"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\": \"8920180326130661344\", \"input\": {}, \"createTime\": \"2019-03-01T14:22:27.464266Z\"}}, \"path_config\": \"generic\", \"bot_id\": \"gce-trusty-32-0258d129-us-east1-b-bs08\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1551450148250693",
"status": "COMPLETED",
"status_changed_ts": "1551457960136678",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9085610388161208528",
"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/8920180326130661344/+/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:43527414267d0610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1551457960138244",
"url": "https://ci.chromium.org/b/8920180326130661344",
"utcnow_ts": "1551457960678183"
}
}
[16:32:40.691] Invocation finished in 2h10m14.546811692s with status SUCCEEDED