[11:51:12.324] New invocation is queued and will start shortly
[11:51:12.324] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[11:51:13.796] Starting the invocation (attempt 1)
[11:51:13.961] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:51:13.961] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:51:13.962] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9098031687168079264",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"72.0.3581.0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9098031687168079264",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[11:51:15.043] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1539604274052849",
"id": "8932601624689921968",
"lease_expiration_ts": "1539723074918257",
"lease_key": "434864787",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3581.0\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1539604274919486",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9098031687168079264",
"scheduler_job_id:infra/Build From Tarball",
"swarming_dimension:builder:Build From Tarball",
"swarming_dimension:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.infra.cron",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:buildbucket_bucket:luci.infra.cron",
"swarming_tag:buildbucket_build_id:8932601624689921968",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Build From Tarball",
"swarming_tag:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8932601624689921968/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:parent_buildername:publish_tarball",
"swarming_tag:pool:luci.infra.cron",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9098031687168079264",
"swarming_tag:scheduler_job_id:infra/Build From Tarball",
"swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:9d4e55f09f057cb6c6ed423e82fe5a8bae98ab2b",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40906265b3781610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1539604274919575",
"url": "https://ci.chromium.org/p/infra/builds/b8932601624689921968",
"utcnow_ts": "1539604275025084"
}
}
[11:51:15.043] Task URL: https://ci.chromium.org/p/infra/builds/b8932601624689921968
[11:51:15.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:2:0) after 1m0s
[11:52:15.060] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:2:0)
[11:52:15.060] Timer tick, asking Buildbucket for the build status
[11:52:15.159] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:52:15.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:3:0) after 1m0s
[11:53:15.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:3:0)
[11:53:15.185] Timer tick, asking Buildbucket for the build status
[11:53:15.295] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:53:15.295] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:4:0) after 1m0s
[11:54:15.335] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:4:0)
[11:54:15.335] Timer tick, asking Buildbucket for the build status
[11:54:15.371] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:54:15.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:5:0) after 1m0s
[11:55:15.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:5:0)
[11:55:15.399] Timer tick, asking Buildbucket for the build status
[11:55:15.536] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:55:15.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:6:0) after 1m0s
[11:56:15.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:6:0)
[11:56:15.553] Timer tick, asking Buildbucket for the build status
[11:56:15.605] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:56:15.605] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:7:0) after 1m0s
[11:57:15.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:7:0)
[11:57:15.628] Timer tick, asking Buildbucket for the build status
[11:57:15.667] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:15.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:8:0) after 1m0s
[11:58:15.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:8:0)
[11:58:15.684] Timer tick, asking Buildbucket for the build status
[11:58:15.749] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:15.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:9:0) after 1m0s
[11:59:15.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:9:0)
[11:59:15.769] Timer tick, asking Buildbucket for the build status
[11:59:15.964] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:15.964] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:10:0) after 1m0s
[12:00:15.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:10:0)
[12:00:15.981] Timer tick, asking Buildbucket for the build status
[12:00:16.043] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:00:16.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:11:0) after 1m0s
[12:01:16.059] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:11:0)
[12:01:16.059] Timer tick, asking Buildbucket for the build status
[12:01:16.198] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:01:16.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:12:0) after 1m0s
[12:02:16.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:12:0)
[12:02:16.252] Timer tick, asking Buildbucket for the build status
[12:02:16.293] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:02:16.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:13:0) after 1m0s
[12:03:16.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:13:0)
[12:03:16.334] Timer tick, asking Buildbucket for the build status
[12:03:16.456] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:03:16.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:14:0) after 1m0s
[12:04:16.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:14:0)
[12:04:16.475] Timer tick, asking Buildbucket for the build status
[12:04:16.527] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:04:16.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:15:0) after 1m0s
[12:05:16.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:15:0)
[12:05:16.547] Timer tick, asking Buildbucket for the build status
[12:05:16.587] Build 8932601624689921968: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:05:16.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:16:0) after 1m0s
[12:05:41.147] Received PubSub notification, asking Buildbucket for the build status
[12:05:41.192] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:16.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:16:0)
[12:06:16.654] Timer tick, asking Buildbucket for the build status
[12:06:16.697] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:16.697] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:18:0) after 1m0s
[12:07:16.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:18:0)
[12:07:16.731] Timer tick, asking Buildbucket for the build status
[12:07:16.837] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:16.837] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:19:0) after 1m0s
[12:08:16.861] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:19:0)
[12:08:16.861] Timer tick, asking Buildbucket for the build status
[12:08:16.935] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:16.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:20:0) after 1m0s
[12:09:16.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:20:0)
[12:09:16.950] Timer tick, asking Buildbucket for the build status
[12:09:17.001] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:17.001] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:21:0) after 1m0s
[12:10:17.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:21:0)
[12:10:17.025] Timer tick, asking Buildbucket for the build status
[12:10:17.117] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:17.117] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:22:0) after 1m0s
[12:11:17.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:22:0)
[12:11:17.133] Timer tick, asking Buildbucket for the build status
[12:11:17.333] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:17.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:23:0) after 1m0s
[12:12:17.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:23:0)
[12:12:17.353] Timer tick, asking Buildbucket for the build status
[12:12:17.413] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:17.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:24:0) after 1m0s
[12:13:17.437] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:24:0)
[12:13:17.437] Timer tick, asking Buildbucket for the build status
[12:13:17.523] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:17.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:25:0) after 1m0s
[12:14:17.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:25:0)
[12:14:17.540] Timer tick, asking Buildbucket for the build status
[12:14:17.711] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:17.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:26:0) after 1m0s
[12:15:17.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:26:0)
[12:15:17.834] Timer tick, asking Buildbucket for the build status
[12:15:17.942] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:17.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:27:0) after 1m0s
[12:16:17.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:27:0)
[12:16:17.970] Timer tick, asking Buildbucket for the build status
[12:16:18.022] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:18.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:28:0) after 1m0s
[12:17:18.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:28:0)
[12:17:18.120] Timer tick, asking Buildbucket for the build status
[12:17:18.318] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:18.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:29:0) after 1m0s
[12:18:18.340] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:29:0)
[12:18:18.340] Timer tick, asking Buildbucket for the build status
[12:18:18.455] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:18.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:30:0) after 1m0s
[12:19:18.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:30:0)
[12:19:18.491] Timer tick, asking Buildbucket for the build status
[12:19:18.718] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:18.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:31:0) after 1m0s
[12:20:19.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:31:0)
[12:20:19.442] Timer tick, asking Buildbucket for the build status
[12:20:19.516] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:19.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:32:0) after 1m0s
[12:21:19.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:32:0)
[12:21:19.631] Timer tick, asking Buildbucket for the build status
[12:21:19.739] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:19.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:33:0) after 1m0s
[12:22:19.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:33:0)
[12:22:19.756] Timer tick, asking Buildbucket for the build status
[12:22:20.176] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:20.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:34:0) after 1m0s
[12:23:20.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:34:0)
[12:23:20.250] Timer tick, asking Buildbucket for the build status
[12:23:20.352] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:20.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:35:0) after 1m0s
[12:24:20.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:35:0)
[12:24:20.504] Timer tick, asking Buildbucket for the build status
[12:24:20.619] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:20.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:36:0) after 1m0s
[12:25:21.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:36:0)
[12:25:21.461] Timer tick, asking Buildbucket for the build status
[12:25:21.542] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:21.542] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:37:0) after 1m0s
[12:26:21.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:37:0)
[12:26:21.565] Timer tick, asking Buildbucket for the build status
[12:26:21.695] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:21.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:38:0) after 1m0s
[12:27:21.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:38:0)
[12:27:21.714] Timer tick, asking Buildbucket for the build status
[12:27:21.772] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:21.772] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:39:0) after 1m0s
[12:28:21.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:39:0)
[12:28:21.793] Timer tick, asking Buildbucket for the build status
[12:28:21.838] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:21.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:40:0) after 1m0s
[12:29:21.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:40:0)
[12:29:21.949] Timer tick, asking Buildbucket for the build status
[12:29:22.006] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:22.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:41:0) after 1m0s
[12:30:22.028] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:41:0)
[12:30:22.028] Timer tick, asking Buildbucket for the build status
[12:30:22.134] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:22.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:42:0) after 1m0s
[12:31:22.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:42:0)
[12:31:22.167] Timer tick, asking Buildbucket for the build status
[12:31:22.245] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:22.245] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:43:0) after 1m0s
[12:32:22.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:43:0)
[12:32:22.264] Timer tick, asking Buildbucket for the build status
[12:32:22.305] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:22.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:44:0) after 1m0s
[12:33:22.325] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:44:0)
[12:33:22.325] Timer tick, asking Buildbucket for the build status
[12:33:22.433] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:22.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:45:0) after 1m0s
[12:34:22.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:45:0)
[12:34:22.464] Timer tick, asking Buildbucket for the build status
[12:34:22.513] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:22.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:46:0) after 1m0s
[12:35:22.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:46:0)
[12:35:22.529] Timer tick, asking Buildbucket for the build status
[12:35:22.601] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:22.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:47:0) after 1m0s
[12:36:22.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:47:0)
[12:36:22.708] Timer tick, asking Buildbucket for the build status
[12:36:22.771] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:22.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:48:0) after 1m0s
[12:37:23.196] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:48:0)
[12:37:23.196] Timer tick, asking Buildbucket for the build status
[12:37:23.307] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:23.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:49:0) after 1m0s
[12:38:23.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:49:0)
[12:38:23.328] Timer tick, asking Buildbucket for the build status
[12:38:23.604] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:23.604] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:50:0) after 1m0s
[12:39:24.503] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:50:0)
[12:39:24.503] Timer tick, asking Buildbucket for the build status
[12:39:24.687] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:24.687] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:51:0) after 1m0s
[12:40:25.043] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:51:0)
[12:40:25.043] Timer tick, asking Buildbucket for the build status
[12:40:25.126] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:25.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:52:0) after 1m0s
[12:41:25.218] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:52:0)
[12:41:25.218] Timer tick, asking Buildbucket for the build status
[12:41:25.305] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:25.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:53:0) after 1m0s
[12:42:25.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:53:0)
[12:42:25.327] Timer tick, asking Buildbucket for the build status
[12:42:25.371] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:25.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:54:0) after 1m0s
[12:43:25.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:54:0)
[12:43:25.399] Timer tick, asking Buildbucket for the build status
[12:43:25.447] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:25.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:55:0) after 1m0s
[12:44:25.464] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:55:0)
[12:44:25.464] Timer tick, asking Buildbucket for the build status
[12:44:25.518] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:25.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:56:0) after 1m0s
[12:45:25.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:56:0)
[12:45:25.538] Timer tick, asking Buildbucket for the build status
[12:45:25.684] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:25.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:57:0) after 1m0s
[12:46:25.700] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:57:0)
[12:46:25.700] Timer tick, asking Buildbucket for the build status
[12:46:25.760] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:25.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:58:0) after 1m0s
[12:47:26.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:58:0)
[12:47:26.322] Timer tick, asking Buildbucket for the build status
[12:47:26.389] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:26.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:59:0) after 1m0s
[12:48:26.627] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:59:0)
[12:48:26.627] Timer tick, asking Buildbucket for the build status
[12:48:26.679] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:26.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:60:0) after 1m0s
[12:49:28.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:60:0)
[12:49:28.170] Timer tick, asking Buildbucket for the build status
[12:49:28.249] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:28.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:61:0) after 1m0s
[12:50:28.814] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:61:0)
[12:50:28.814] Timer tick, asking Buildbucket for the build status
[12:50:29.096] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:29.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:62:0) after 1m0s
[12:51:29.653] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:62:0)
[12:51:29.653] Timer tick, asking Buildbucket for the build status
[12:51:29.706] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:29.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:63:0) after 1m0s
[12:52:29.754] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:63:0)
[12:52:29.754] Timer tick, asking Buildbucket for the build status
[12:52:29.807] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:29.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:64:0) after 1m0s
[12:53:29.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:64:0)
[12:53:29.825] Timer tick, asking Buildbucket for the build status
[12:53:29.874] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:29.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:65:0) after 1m0s
[12:54:30.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:65:0)
[12:54:30.020] Timer tick, asking Buildbucket for the build status
[12:54:30.151] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:30.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:66:0) after 1m0s
[12:55:30.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:66:0)
[12:55:30.170] Timer tick, asking Buildbucket for the build status
[12:55:30.341] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:30.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:67:0) after 1m0s
[12:56:30.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:67:0)
[12:56:30.360] Timer tick, asking Buildbucket for the build status
[12:56:30.508] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:30.508] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:68:0) after 1m0s
[12:57:30.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:68:0)
[12:57:30.529] Timer tick, asking Buildbucket for the build status
[12:57:30.600] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:30.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:69:0) after 1m0s
[12:58:30.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:69:0)
[12:58:30.620] Timer tick, asking Buildbucket for the build status
[12:58:30.679] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:30.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:70:0) after 1m0s
[12:59:30.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:70:0)
[12:59:30.697] Timer tick, asking Buildbucket for the build status
[12:59:30.875] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:30.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:71:0) after 1m0s
[13:00:30.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:71:0)
[13:00:30.896] Timer tick, asking Buildbucket for the build status
[13:00:31.298] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:31.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:72:0) after 1m0s
[13:01:31.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:72:0)
[13:01:31.350] Timer tick, asking Buildbucket for the build status
[13:01:31.438] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:31.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:73:0) after 1m0s
[13:02:31.472] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:73:0)
[13:02:31.472] Timer tick, asking Buildbucket for the build status
[13:02:31.531] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:31.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:74:0) after 1m0s
[13:03:31.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:74:0)
[13:03:31.554] Timer tick, asking Buildbucket for the build status
[13:03:31.618] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:31.618] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:75:0) after 1m0s
[13:04:31.720] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:75:0)
[13:04:31.720] Timer tick, asking Buildbucket for the build status
[13:04:31.777] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:31.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:76:0) after 1m0s
[13:05:32.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:76:0)
[13:05:32.309] Timer tick, asking Buildbucket for the build status
[13:05:32.420] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:32.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:77:0) after 1m0s
[13:06:33.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:77:0)
[13:06:33.112] Timer tick, asking Buildbucket for the build status
[13:06:33.213] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:33.213] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:78:0) after 1m0s
[13:07:33.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:78:0)
[13:07:33.244] Timer tick, asking Buildbucket for the build status
[13:07:33.312] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:33.312] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:79:0) after 1m0s
[13:08:33.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:79:0)
[13:08:33.396] Timer tick, asking Buildbucket for the build status
[13:08:33.488] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:33.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:80:0) after 1m0s
[13:09:33.507] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:80:0)
[13:09:33.507] Timer tick, asking Buildbucket for the build status
[13:09:33.541] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:33.541] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:81:0) after 1m0s
[13:10:33.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:81:0)
[13:10:33.663] Timer tick, asking Buildbucket for the build status
[13:10:33.706] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:33.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:82:0) after 1m0s
[13:11:33.782] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:82:0)
[13:11:33.782] Timer tick, asking Buildbucket for the build status
[13:11:33.847] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:33.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:83:0) after 1m0s
[13:12:33.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:83:0)
[13:12:33.869] Timer tick, asking Buildbucket for the build status
[13:12:33.922] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:33.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:84:0) after 1m0s
[13:13:34.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:84:0)
[13:13:34.066] Timer tick, asking Buildbucket for the build status
[13:13:34.160] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:34.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:85:0) after 1m0s
[13:14:34.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:85:0)
[13:14:34.632] Timer tick, asking Buildbucket for the build status
[13:14:34.704] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:34.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:86:0) after 1m0s
[13:15:34.725] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:86:0)
[13:15:34.725] Timer tick, asking Buildbucket for the build status
[13:15:34.795] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:34.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:87:0) after 1m0s
[13:16:35.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:87:0)
[13:16:35.199] Timer tick, asking Buildbucket for the build status
[13:16:35.283] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:35.284] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:88:0) after 1m0s
[13:17:35.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:88:0)
[13:17:35.403] Timer tick, asking Buildbucket for the build status
[13:17:35.510] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:35.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:89:0) after 1m0s
[13:18:36.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:89:0)
[13:18:36.650] Timer tick, asking Buildbucket for the build status
[13:18:36.711] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:36.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:90:0) after 1m0s
[13:19:37.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:90:0)
[13:19:37.174] Timer tick, asking Buildbucket for the build status
[13:19:37.226] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:37.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:91:0) after 1m0s
[13:20:37.828] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:91:0)
[13:20:37.828] Timer tick, asking Buildbucket for the build status
[13:20:37.903] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:37.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:92:0) after 1m0s
[13:21:38.089] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:92:0)
[13:21:38.089] Timer tick, asking Buildbucket for the build status
[13:21:38.138] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:38.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:93:0) after 1m0s
[13:22:38.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:93:0)
[13:22:38.184] Timer tick, asking Buildbucket for the build status
[13:22:38.240] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:38.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:94:0) after 1m0s
[13:23:38.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:94:0)
[13:23:38.510] Timer tick, asking Buildbucket for the build status
[13:23:38.596] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:38.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:95:0) after 1m0s
[13:24:38.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:95:0)
[13:24:38.662] Timer tick, asking Buildbucket for the build status
[13:24:38.728] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:38.729] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:96:0) after 1m0s
[13:25:38.951] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:96:0)
[13:25:38.951] Timer tick, asking Buildbucket for the build status
[13:25:39.050] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:39.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:97:0) after 1m0s
[13:26:39.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:97:0)
[13:26:39.431] Timer tick, asking Buildbucket for the build status
[13:26:39.482] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:39.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:98:0) after 1m0s
[13:27:40.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:98:0)
[13:27:40.026] Timer tick, asking Buildbucket for the build status
[13:27:40.100] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:40.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:99:0) after 1m0s
[13:28:40.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:99:0)
[13:28:40.629] Timer tick, asking Buildbucket for the build status
[13:28:40.758] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:40.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:100:0) after 1m0s
[13:29:41.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:100:0)
[13:29:41.341] Timer tick, asking Buildbucket for the build status
[13:29:41.390] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:41.390] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:101:0) after 1m0s
[13:30:42.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:101:0)
[13:30:42.042] Timer tick, asking Buildbucket for the build status
[13:30:42.107] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:42.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:102:0) after 1m0s
[13:31:42.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:102:0)
[13:31:42.765] Timer tick, asking Buildbucket for the build status
[13:31:42.863] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:42.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:103:0) after 1m0s
[13:32:43.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:103:0)
[13:32:43.995] Timer tick, asking Buildbucket for the build status
[13:32:44.095] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:44.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:104:0) after 1m0s
[13:33:44.759] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:104:0)
[13:33:44.759] Timer tick, asking Buildbucket for the build status
[13:33:44.929] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:44.929] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:105:0) after 1m0s
[13:34:45.803] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:105:0)
[13:34:45.803] Timer tick, asking Buildbucket for the build status
[13:34:45.896] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:45.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:106:0) after 1m0s
[13:35:46.417] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:106:0)
[13:35:46.417] Timer tick, asking Buildbucket for the build status
[13:35:46.628] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:46.628] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:107:0) after 1m0s
[13:36:46.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:107:0)
[13:36:46.649] Timer tick, asking Buildbucket for the build status
[13:36:46.702] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:46.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:108:0) after 1m0s
[13:37:47.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:108:0)
[13:37:47.133] Timer tick, asking Buildbucket for the build status
[13:37:47.298] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:47.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:109:0) after 1m0s
[13:38:47.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:109:0)
[13:38:47.871] Timer tick, asking Buildbucket for the build status
[13:38:47.924] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:47.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:110:0) after 1m0s
[13:39:48.393] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:110:0)
[13:39:48.393] Timer tick, asking Buildbucket for the build status
[13:39:48.916] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:48.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:111:0) after 1m0s
[13:40:49.259] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:111:0)
[13:40:49.259] Timer tick, asking Buildbucket for the build status
[13:40:49.318] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:49.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:112:0) after 1m0s
[13:41:49.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:112:0)
[13:41:49.342] Timer tick, asking Buildbucket for the build status
[13:41:49.416] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:49.416] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:113:0) after 1m0s
[13:42:49.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:113:0)
[13:42:49.442] Timer tick, asking Buildbucket for the build status
[13:42:49.690] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:49.690] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:114:0) after 1m0s
[13:43:49.712] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:114:0)
[13:43:49.712] Timer tick, asking Buildbucket for the build status
[13:43:49.863] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:49.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:115:0) after 1m0s
[13:44:49.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:115:0)
[13:44:49.897] Timer tick, asking Buildbucket for the build status
[13:44:49.943] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:49.943] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:116:0) after 1m0s
[13:45:49.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:116:0)
[13:45:49.971] Timer tick, asking Buildbucket for the build status
[13:45:50.226] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:50.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:117:0) after 1m0s
[13:46:50.413] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:117:0)
[13:46:50.413] Timer tick, asking Buildbucket for the build status
[13:46:50.499] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:50.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:118:0) after 1m0s
[13:47:50.589] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:118:0)
[13:47:50.589] Timer tick, asking Buildbucket for the build status
[13:47:50.789] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:50.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:119:0) after 1m0s
[13:48:51.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:119:0)
[13:48:51.980] Timer tick, asking Buildbucket for the build status
[13:48:52.058] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:52.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:120:0) after 1m0s
[13:49:52.118] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:120:0)
[13:49:52.118] Timer tick, asking Buildbucket for the build status
[13:49:52.252] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:52.252] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:121:0) after 1m0s
[13:50:52.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:121:0)
[13:50:52.734] Timer tick, asking Buildbucket for the build status
[13:50:52.882] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:52.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:122:0) after 1m0s
[13:51:52.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:122:0)
[13:51:52.905] Timer tick, asking Buildbucket for the build status
[13:51:53.145] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:53.145] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:123:0) after 1m0s
[13:52:53.445] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:123:0)
[13:52:53.445] Timer tick, asking Buildbucket for the build status
[13:52:53.537] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:53.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:124:0) after 1m0s
[13:53:53.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:124:0)
[13:53:53.599] Timer tick, asking Buildbucket for the build status
[13:53:53.645] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:53:53.645] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:125:0) after 1m0s
[13:54:53.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:125:0)
[13:54:53.704] Timer tick, asking Buildbucket for the build status
[13:54:53.766] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:54:53.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:126:0) after 1m0s
[13:55:53.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:126:0)
[13:55:53.994] Timer tick, asking Buildbucket for the build status
[13:55:54.100] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:55:54.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:127:0) after 1m0s
[13:56:55.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:127:0)
[13:56:55.066] Timer tick, asking Buildbucket for the build status
[13:56:55.360] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:56:55.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:128:0) after 1m0s
[13:57:55.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:128:0)
[13:57:55.515] Timer tick, asking Buildbucket for the build status
[13:57:55.599] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:57:55.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:129:0) after 1m0s
[13:58:55.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:129:0)
[13:58:55.661] Timer tick, asking Buildbucket for the build status
[13:58:55.862] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:58:55.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:130:0) after 1m0s
[13:59:56.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:130:0)
[13:59:56.477] Timer tick, asking Buildbucket for the build status
[13:59:56.569] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:59:56.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:131:0) after 1m0s
[14:00:57.742] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:131:0)
[14:00:57.742] Timer tick, asking Buildbucket for the build status
[14:00:57.994] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:00:57.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:132:0) after 1m0s
[14:01:58.545] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:132:0)
[14:01:58.545] Timer tick, asking Buildbucket for the build status
[14:01:58.631] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:01:58.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:133:0) after 1m0s
[14:02:58.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:133:0)
[14:02:58.735] Timer tick, asking Buildbucket for the build status
[14:02:58.819] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:02:58.819] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:134:0) after 1m0s
[14:03:58.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:134:0)
[14:03:58.885] Timer tick, asking Buildbucket for the build status
[14:03:58.958] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:03:58.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:135:0) after 1m0s
[14:04:59.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:135:0)
[14:04:59.125] Timer tick, asking Buildbucket for the build status
[14:04:59.189] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:04:59.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:136:0) after 1m0s
[14:05:59.228] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:136:0)
[14:05:59.228] Timer tick, asking Buildbucket for the build status
[14:05:59.316] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:05:59.316] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:137:0) after 1m0s
[14:07:00.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:137:0)
[14:07:00.497] Timer tick, asking Buildbucket for the build status
[14:07:00.597] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:07:00.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:138:0) after 1m0s
[14:08:00.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:138:0)
[14:08:00.856] Timer tick, asking Buildbucket for the build status
[14:08:10.825] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:08:10.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:139:0) after 1m0s
[14:09:10.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:139:0)
[14:09:10.852] Timer tick, asking Buildbucket for the build status
[14:09:10.899] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:09:10.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:140:0) after 1m0s
[14:10:10.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:140:0)
[14:10:10.929] Timer tick, asking Buildbucket for the build status
[14:10:11.043] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:10:11.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:141:0) after 1m0s
[14:11:11.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:141:0)
[14:11:11.064] Timer tick, asking Buildbucket for the build status
[14:11:11.158] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:11:11.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:142:0) after 1m0s
[14:12:11.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:142:0)
[14:12:11.197] Timer tick, asking Buildbucket for the build status
[14:12:11.327] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:12:11.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:143:0) after 1m0s
[14:13:11.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:143:0)
[14:13:11.353] Timer tick, asking Buildbucket for the build status
[14:13:11.433] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:13:11.433] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:144:0) after 1m0s
[14:14:11.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:144:0)
[14:14:11.460] Timer tick, asking Buildbucket for the build status
[14:14:11.530] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:14:11.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:145:0) after 1m0s
[14:15:11.614] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:145:0)
[14:15:11.614] Timer tick, asking Buildbucket for the build status
[14:15:11.780] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:15:11.780] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:146:0) after 1m0s
[14:16:11.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:146:0)
[14:16:11.805] Timer tick, asking Buildbucket for the build status
[14:16:11.912] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:16:11.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:147:0) after 1m0s
[14:17:11.937] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:147:0)
[14:17:11.937] Timer tick, asking Buildbucket for the build status
[14:17:11.983] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:17:11.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:148:0) after 1m0s
[14:18:12.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:148:0)
[14:18:12.007] Timer tick, asking Buildbucket for the build status
[14:18:12.116] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:12.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:149:0) after 1m0s
[14:19:12.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:149:0)
[14:19:12.144] Timer tick, asking Buildbucket for the build status
[14:19:12.313] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:12.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:150:0) after 1m0s
[14:20:12.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:150:0)
[14:20:12.407] Timer tick, asking Buildbucket for the build status
[14:20:12.490] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:12.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:151:0) after 1m0s
[14:21:12.554] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:151:0)
[14:21:12.554] Timer tick, asking Buildbucket for the build status
[14:21:12.643] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:12.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:152:0) after 1m0s
[14:22:12.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:152:0)
[14:22:12.670] Timer tick, asking Buildbucket for the build status
[14:22:12.744] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:12.744] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:153:0) after 1m0s
[14:23:12.769] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:153:0)
[14:23:12.769] Timer tick, asking Buildbucket for the build status
[14:23:12.850] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:12.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:154:0) after 1m0s
[14:24:12.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:154:0)
[14:24:12.876] Timer tick, asking Buildbucket for the build status
[14:24:13.054] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:13.054] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:155:0) after 1m0s
[14:25:13.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:155:0)
[14:25:13.094] Timer tick, asking Buildbucket for the build status
[14:25:13.189] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:13.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:156:0) after 1m0s
[14:26:13.395] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:156:0)
[14:26:13.395] Timer tick, asking Buildbucket for the build status
[14:26:13.533] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:13.533] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:157:0) after 1m0s
[14:27:13.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:157:0)
[14:27:13.558] Timer tick, asking Buildbucket for the build status
[14:27:13.734] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:13.734] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:158:0) after 1m0s
[14:28:13.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:158:0)
[14:28:13.817] Timer tick, asking Buildbucket for the build status
[14:28:13.903] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:13.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:159:0) after 1m0s
[14:29:13.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:159:0)
[14:29:13.925] Timer tick, asking Buildbucket for the build status
[14:29:14.153] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:14.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:160:0) after 1m0s
[14:30:14.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:160:0)
[14:30:14.245] Timer tick, asking Buildbucket for the build status
[14:30:14.415] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:14.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:161:0) after 1m0s
[14:31:14.469] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:161:0)
[14:31:14.469] Timer tick, asking Buildbucket for the build status
[14:31:14.547] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:14.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:162:0) after 1m0s
[14:32:14.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:162:0)
[14:32:14.572] Timer tick, asking Buildbucket for the build status
[14:32:14.629] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:14.629] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:163:0) after 1m0s
[14:33:14.656] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:163:0)
[14:33:14.656] Timer tick, asking Buildbucket for the build status
[14:33:14.912] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:14.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:164:0) after 1m0s
[14:34:14.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:164:0)
[14:34:14.940] Timer tick, asking Buildbucket for the build status
[14:34:15.002] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:15.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:165:0) after 1m0s
[14:35:15.026] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:165:0)
[14:35:15.026] Timer tick, asking Buildbucket for the build status
[14:35:15.195] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:15.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:166:0) after 1m0s
[14:36:15.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:166:0)
[14:36:15.221] Timer tick, asking Buildbucket for the build status
[14:36:15.269] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:15.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:167:0) after 1m0s
[14:37:15.349] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:167:0)
[14:37:15.349] Timer tick, asking Buildbucket for the build status
[14:37:15.436] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:15.436] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:168:0) after 1m0s
[14:38:15.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:168:0)
[14:38:15.457] Timer tick, asking Buildbucket for the build status
[14:38:15.514] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:15.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:169:0) after 1m0s
[14:39:15.639] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:169:0)
[14:39:15.639] Timer tick, asking Buildbucket for the build status
[14:39:15.704] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:15.704] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:170:0) after 1m0s
[14:40:16.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:170:0)
[14:40:16.358] Timer tick, asking Buildbucket for the build status
[14:40:16.414] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:16.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:171:0) after 1m0s
[14:41:16.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:171:0)
[14:41:16.807] Timer tick, asking Buildbucket for the build status
[14:41:16.854] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:16.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:172:0) after 1m0s
[14:42:17.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:172:0)
[14:42:17.301] Timer tick, asking Buildbucket for the build status
[14:42:17.527] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:17.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:173:0) after 1m0s
[14:43:17.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:173:0)
[14:43:17.984] Timer tick, asking Buildbucket for the build status
[14:43:18.172] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:18.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:174:0) after 1m0s
[14:44:18.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:174:0)
[14:44:18.203] Timer tick, asking Buildbucket for the build status
[14:44:18.240] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:18.240] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:175:0) after 1m0s
[14:45:18.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:175:0)
[14:45:18.549] Timer tick, asking Buildbucket for the build status
[14:45:18.663] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:18.663] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:176:0) after 1m0s
[14:46:20.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:176:0)
[14:46:20.199] Timer tick, asking Buildbucket for the build status
[14:46:20.279] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:20.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:177:0) after 1m0s
[14:47:21.214] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:177:0)
[14:47:21.214] Timer tick, asking Buildbucket for the build status
[14:47:21.468] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:21.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:178:0) after 1m0s
[14:48:21.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:178:0)
[14:48:21.757] Timer tick, asking Buildbucket for the build status
[14:48:21.832] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:21.832] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:179:0) after 1m0s
[14:49:22.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:179:0)
[14:49:22.021] Timer tick, asking Buildbucket for the build status
[14:49:22.187] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:22.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:180:0) after 1m0s
[14:50:22.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:180:0)
[14:50:22.221] Timer tick, asking Buildbucket for the build status
[14:50:22.290] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:22.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:181:0) after 1m0s
[14:51:22.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:181:0)
[14:51:22.360] Timer tick, asking Buildbucket for the build status
[14:51:22.442] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:22.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:182:0) after 1m0s
[14:52:22.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:182:0)
[14:52:22.463] Timer tick, asking Buildbucket for the build status
[14:52:22.569] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:22.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:183:0) after 1m0s
[14:53:22.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:183:0)
[14:53:22.590] Timer tick, asking Buildbucket for the build status
[14:53:22.651] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:22.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:184:0) after 1m0s
[14:54:22.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:184:0)
[14:54:22.675] Timer tick, asking Buildbucket for the build status
[14:54:22.771] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:22.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:185:0) after 1m0s
[14:55:22.914] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:185:0)
[14:55:22.914] Timer tick, asking Buildbucket for the build status
[14:55:23.131] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:23.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:186:0) after 1m0s
[14:56:24.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:186:0)
[14:56:24.246] Timer tick, asking Buildbucket for the build status
[14:56:24.311] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:24.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:187:0) after 1m0s
[14:57:25.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:187:0)
[14:57:25.377] Timer tick, asking Buildbucket for the build status
[14:57:25.478] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:25.478] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:188:0) after 1m0s
[14:58:25.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:188:0)
[14:58:25.501] Timer tick, asking Buildbucket for the build status
[14:58:25.692] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:25.692] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:189:0) after 1m0s
[14:59:25.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:189:0)
[14:59:25.731] Timer tick, asking Buildbucket for the build status
[14:59:25.804] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:25.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:190:0) after 1m0s
[15:00:25.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:190:0)
[15:00:25.954] Timer tick, asking Buildbucket for the build status
[15:00:26.046] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:26.046] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:191:0) after 1m0s
[15:01:26.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:191:0)
[15:01:26.067] Timer tick, asking Buildbucket for the build status
[15:01:26.194] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:26.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:192:0) after 1m0s
[15:02:26.215] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:192:0)
[15:02:26.215] Timer tick, asking Buildbucket for the build status
[15:02:26.269] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:26.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:193:0) after 1m0s
[15:03:26.501] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:193:0)
[15:03:26.501] Timer tick, asking Buildbucket for the build status
[15:03:26.654] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:26.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:194:0) after 1m0s
[15:04:27.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:194:0)
[15:04:27.461] Timer tick, asking Buildbucket for the build status
[15:04:27.602] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:27.602] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:195:0) after 1m0s
[15:05:27.767] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:195:0)
[15:05:27.767] Timer tick, asking Buildbucket for the build status
[15:05:27.852] Build 8932601624689921968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:27.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098031687168079264:196:0) after 1m0s
[15:06:03.979] Received PubSub notification, asking Buildbucket for the build status
[15:06:04.043] Build 8932601624689921968: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[15:06:04.043] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1539615962902897",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1539604274052849",
"failure_reason": "INFRA_FAILURE",
"id": "8932601624689921968",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3581.0\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"build_run_result\": {\"infraFailure\": {\"text\": \"kitchen crashed or got killed\", \"bootstrapperCallStack\": [], \"type\": \"BOOTSTRAPPER_ERROR\"}, \"recipeResult\": null, \"recipeExitCode\": null, \"recipe\": null, \"annotationUrl\": \"\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"inside_docker\": [\"0\"], \"python\": [\"2.7.6\"], \"builder\": [\"Build From Tarball\"], \"kvm\": [\"1\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"server_version\": [\"3809-a31bbe1\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-c\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-canary-e973df7c-us-central1-c-xkdd\"], \"pool\": [\"luci.infra.cron\"]}}, \"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1539605140300500",
"status": "COMPLETED",
"status_changed_ts": "1539615963361489",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9098031687168079264",
"scheduler_job_id:infra/Build From Tarball",
"swarming_dimension:builder:Build From Tarball",
"swarming_dimension:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Ubuntu-14.04",
"swarming_dimension:pool:luci.infra.cron",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:buildbucket_bucket:luci.infra.cron",
"swarming_tag:buildbucket_build_id:8932601624689921968",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:4c790cbde9342069ed9b59b6709c8807b14f2913",
"swarming_tag:builder:Build From Tarball",
"swarming_tag:caches:builder_99d5fe30e8b6c78ca136ab5e547c67467b176b4f7d67cf49cf682cf48a330f19_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8932601624689921968/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Ubuntu-14.04",
"swarming_tag:parent_buildername:publish_tarball",
"swarming_tag:pool:luci.infra.cron",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_tag:scheduler_invocation_id:9098031687168079264",
"swarming_tag:scheduler_job_id:infra/Build From Tarball",
"swarming_tag:service_account:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:9d4e55f09f057cb6c6ed423e82fe5a8bae98ab2b",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40906265b3781610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1539615963362319",
"url": "https://ci.chromium.org/p/infra/builds/b8932601624689921968",
"utcnow_ts": "1539615964030399"
}
}
[15:06:04.043] Invocation finished in 3h14m51.737469665s with status FAILED