[10:33:26.370] New invocation is queued and will start shortly
[10:33:26.370] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:33:27.640] Starting the invocation (attempt 1)
[10:33:27.672] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:33:27.672] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:33:27.672] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9096949416182749152",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"72.0.3593.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:9096949416182749152",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:33:28.706] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1540636407791593",
"id": "8931519354022785328",
"lease_expiration_ts": "1540755208607175",
"lease_key": "1791528932",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3593.0\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1540636408608116",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9096949416182749152",
"scheduler_job_id:infra/Build From Tarball",
"swarming_dimension:builder:Build From Tarball",
"swarming_dimension:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_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:infra/cron",
"swarming_tag:buildbucket_build_id:8931519354022785328",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:c1e4e074f3337ff2dbbfb9aa3b0475a61adb09a6",
"swarming_tag:builder:Build From Tarball",
"swarming_tag:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8931519354022785328/+/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:9096949416182749152",
"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:d41fa26fabd353b1ddcf98207590a9e56d6dfcea",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40cde781de4c4610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1540636408608196",
"url": "https://ci.chromium.org/b/8931519354022785328",
"utcnow_ts": "1540636408694823"
}
}
[10:33:28.706] Task URL: https://ci.chromium.org/b/8931519354022785328
[10:33:28.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:2:0) after 1m0s
[10:34:02.966] Received PubSub notification, asking Buildbucket for the build status
[10:34:03.023] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:28.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:2:0)
[10:34:28.724] Timer tick, asking Buildbucket for the build status
[10:34:28.864] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:28.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:4:0) after 1m0s
[10:35:28.892] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:4:0)
[10:35:28.892] Timer tick, asking Buildbucket for the build status
[10:35:29.090] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:29.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:5:0) after 1m0s
[10:36:29.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:5:0)
[10:36:29.109] Timer tick, asking Buildbucket for the build status
[10:36:29.201] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:29.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:6:0) after 1m0s
[10:37:29.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:6:0)
[10:37:29.222] Timer tick, asking Buildbucket for the build status
[10:37:29.307] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:29.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:7:0) after 1m0s
[10:38:29.326] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:7:0)
[10:38:29.326] Timer tick, asking Buildbucket for the build status
[10:38:29.440] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:29.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:8:0) after 1m0s
[10:39:29.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:8:0)
[10:39:29.462] Timer tick, asking Buildbucket for the build status
[10:39:29.531] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:29.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:9:0) after 1m0s
[10:40:29.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:9:0)
[10:40:29.562] Timer tick, asking Buildbucket for the build status
[10:40:29.630] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:29.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:10:0) after 1m0s
[10:41:29.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:10:0)
[10:41:29.650] Timer tick, asking Buildbucket for the build status
[10:41:29.755] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:29.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:11:0) after 1m0s
[10:42:29.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:11:0)
[10:42:29.804] Timer tick, asking Buildbucket for the build status
[10:42:29.847] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:29.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:12:0) after 1m0s
[10:43:29.920] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:12:0)
[10:43:29.920] Timer tick, asking Buildbucket for the build status
[10:43:30.198] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:30.198] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:13:0) after 1m0s
[10:44:30.282] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:13:0)
[10:44:30.282] Timer tick, asking Buildbucket for the build status
[10:44:30.464] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:30.464] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:14:0) after 1m0s
[10:45:30.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:14:0)
[10:45:30.486] Timer tick, asking Buildbucket for the build status
[10:45:30.527] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:30.527] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:15:0) after 1m0s
[10:46:30.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:15:0)
[10:46:30.544] Timer tick, asking Buildbucket for the build status
[10:46:30.603] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:30.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:16:0) after 1m0s
[10:47:30.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:16:0)
[10:47:30.628] Timer tick, asking Buildbucket for the build status
[10:47:30.699] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:30.699] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:17:0) after 1m0s
[10:48:30.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:17:0)
[10:48:30.719] Timer tick, asking Buildbucket for the build status
[10:48:30.755] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:30.755] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:18:0) after 1m0s
[10:49:30.889] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:18:0)
[10:49:30.889] Timer tick, asking Buildbucket for the build status
[10:49:30.971] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:30.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:19:0) after 1m0s
[10:50:31.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:19:0)
[10:50:31.021] Timer tick, asking Buildbucket for the build status
[10:50:31.097] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:31.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:20:0) after 1m0s
[10:51:31.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:20:0)
[10:51:31.171] Timer tick, asking Buildbucket for the build status
[10:51:31.318] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:31.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:21:0) after 1m0s
[10:52:31.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:21:0)
[10:52:31.461] Timer tick, asking Buildbucket for the build status
[10:52:31.518] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:31.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:22:0) after 1m0s
[10:53:31.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:22:0)
[10:53:31.538] Timer tick, asking Buildbucket for the build status
[10:53:31.667] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:31.667] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:23:0) after 1m0s
[10:54:31.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:23:0)
[10:54:31.781] Timer tick, asking Buildbucket for the build status
[10:54:31.817] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:31.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:24:0) after 1m0s
[10:55:31.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:24:0)
[10:55:31.862] Timer tick, asking Buildbucket for the build status
[10:55:31.947] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:31.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:25:0) after 1m0s
[10:56:31.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:25:0)
[10:56:31.969] Timer tick, asking Buildbucket for the build status
[10:56:32.146] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:32.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:26:0) after 1m0s
[10:57:32.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:26:0)
[10:57:32.170] Timer tick, asking Buildbucket for the build status
[10:57:32.236] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:32.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:27:0) after 1m0s
[10:58:32.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:27:0)
[10:58:32.256] Timer tick, asking Buildbucket for the build status
[10:58:32.313] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:32.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:28:0) after 1m0s
[10:59:32.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:28:0)
[10:59:32.337] Timer tick, asking Buildbucket for the build status
[10:59:32.397] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:32.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:29:0) after 1m0s
[11:00:32.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:29:0)
[11:00:32.433] Timer tick, asking Buildbucket for the build status
[11:00:32.490] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:32.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:30:0) after 1m0s
[11:01:32.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:30:0)
[11:01:32.571] Timer tick, asking Buildbucket for the build status
[11:01:32.637] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:32.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:31:0) after 1m0s
[11:02:32.724] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:31:0)
[11:02:32.724] Timer tick, asking Buildbucket for the build status
[11:02:32.796] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:32.796] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:32:0) after 1m0s
[11:03:32.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:32:0)
[11:03:32.855] Timer tick, asking Buildbucket for the build status
[11:03:32.903] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:32.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:33:0) after 1m0s
[11:04:32.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:33:0)
[11:04:32.995] Timer tick, asking Buildbucket for the build status
[11:04:33.249] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:33.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:34:0) after 1m0s
[11:05:33.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:34:0)
[11:05:33.324] Timer tick, asking Buildbucket for the build status
[11:05:33.385] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:33.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:35:0) after 1m0s
[11:06:33.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:35:0)
[11:06:33.410] Timer tick, asking Buildbucket for the build status
[11:06:33.498] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:33.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:36:0) after 1m0s
[11:07:33.597] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:36:0)
[11:07:33.597] Timer tick, asking Buildbucket for the build status
[11:07:33.657] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:33.657] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:37:0) after 1m0s
[11:08:33.784] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:37:0)
[11:08:33.784] Timer tick, asking Buildbucket for the build status
[11:08:33.847] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:33.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:38:0) after 1m0s
[11:09:33.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:38:0)
[11:09:33.938] Timer tick, asking Buildbucket for the build status
[11:09:34.007] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:34.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:39:0) after 1m0s
[11:10:34.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:39:0)
[11:10:34.033] Timer tick, asking Buildbucket for the build status
[11:10:34.098] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:34.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:40:0) after 1m0s
[11:11:34.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:40:0)
[11:11:34.171] Timer tick, asking Buildbucket for the build status
[11:11:34.307] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:34.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:41:0) after 1m0s
[11:12:34.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:41:0)
[11:12:34.332] Timer tick, asking Buildbucket for the build status
[11:12:34.394] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:34.394] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:42:0) after 1m0s
[11:13:34.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:42:0)
[11:13:34.471] Timer tick, asking Buildbucket for the build status
[11:13:34.529] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:34.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:43:0) after 1m0s
[11:14:34.555] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:43:0)
[11:14:34.555] Timer tick, asking Buildbucket for the build status
[11:14:34.621] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:34.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:44:0) after 1m0s
[11:15:34.681] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:44:0)
[11:15:34.681] Timer tick, asking Buildbucket for the build status
[11:15:34.719] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:34.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:45:0) after 1m0s
[11:16:34.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:45:0)
[11:16:34.758] Timer tick, asking Buildbucket for the build status
[11:16:34.881] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:34.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:46:0) after 1m0s
[11:17:34.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:46:0)
[11:17:34.903] Timer tick, asking Buildbucket for the build status
[11:17:34.957] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:34.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:47:0) after 1m0s
[11:18:34.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:47:0)
[11:18:34.983] Timer tick, asking Buildbucket for the build status
[11:18:35.055] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:35.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:48:0) after 1m0s
[11:19:35.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:48:0)
[11:19:35.136] Timer tick, asking Buildbucket for the build status
[11:19:35.195] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:35.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:49:0) after 1m0s
[11:20:35.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:49:0)
[11:20:35.307] Timer tick, asking Buildbucket for the build status
[11:20:35.495] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:35.495] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:50:0) after 1m0s
[11:21:35.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:50:0)
[11:21:35.529] Timer tick, asking Buildbucket for the build status
[11:21:35.588] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:35.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:51:0) after 1m0s
[11:22:35.634] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:51:0)
[11:22:35.634] Timer tick, asking Buildbucket for the build status
[11:22:35.696] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:35.696] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:52:0) after 1m0s
[11:23:35.729] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:52:0)
[11:23:35.729] Timer tick, asking Buildbucket for the build status
[11:23:35.881] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:35.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:53:0) after 1m0s
[11:24:35.917] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:53:0)
[11:24:35.917] Timer tick, asking Buildbucket for the build status
[11:24:35.984] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:35.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:54:0) after 1m0s
[11:25:36.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:54:0)
[11:25:36.007] Timer tick, asking Buildbucket for the build status
[11:25:36.068] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:36.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:55:0) after 1m0s
[11:26:36.093] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:55:0)
[11:26:36.093] Timer tick, asking Buildbucket for the build status
[11:26:36.184] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:36.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:56:0) after 1m0s
[11:27:36.208] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:56:0)
[11:27:36.208] Timer tick, asking Buildbucket for the build status
[11:27:36.287] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:36.287] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:57:0) after 1m0s
[11:28:36.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:57:0)
[11:28:36.430] Timer tick, asking Buildbucket for the build status
[11:28:36.512] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:36.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:58:0) after 1m0s
[11:29:36.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:58:0)
[11:29:36.644] Timer tick, asking Buildbucket for the build status
[11:29:36.701] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:36.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:59:0) after 1m0s
[11:30:36.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:59:0)
[11:30:36.761] Timer tick, asking Buildbucket for the build status
[11:30:36.923] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:36.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:60:0) after 1m0s
[11:31:36.949] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:60:0)
[11:31:36.949] Timer tick, asking Buildbucket for the build status
[11:31:37.009] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:37.009] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:61:0) after 1m0s
[11:32:37.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:61:0)
[11:32:37.039] Timer tick, asking Buildbucket for the build status
[11:32:37.075] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:37.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:62:0) after 1m0s
[11:33:37.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:62:0)
[11:33:37.170] Timer tick, asking Buildbucket for the build status
[11:33:37.236] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:37.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:63:0) after 1m0s
[11:34:37.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:63:0)
[11:34:37.260] Timer tick, asking Buildbucket for the build status
[11:34:37.413] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:37.413] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:64:0) after 1m0s
[11:35:37.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:64:0)
[11:35:37.433] Timer tick, asking Buildbucket for the build status
[11:35:37.481] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:37.481] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:65:0) after 1m0s
[11:36:37.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:65:0)
[11:36:37.550] Timer tick, asking Buildbucket for the build status
[11:36:37.712] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:37.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:66:0) after 1m0s
[11:37:37.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:66:0)
[11:37:37.736] Timer tick, asking Buildbucket for the build status
[11:37:37.807] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:37.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:67:0) after 1m0s
[11:38:37.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:67:0)
[11:38:37.842] Timer tick, asking Buildbucket for the build status
[11:38:37.958] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:37.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:68:0) after 1m0s
[11:39:37.982] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:68:0)
[11:39:37.982] Timer tick, asking Buildbucket for the build status
[11:39:38.114] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:38.114] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:69:0) after 1m0s
[11:40:38.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:69:0)
[11:40:38.150] Timer tick, asking Buildbucket for the build status
[11:40:38.214] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:38.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:70:0) after 1m0s
[11:41:38.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:70:0)
[11:41:38.264] Timer tick, asking Buildbucket for the build status
[11:41:38.449] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:38.449] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:71:0) after 1m0s
[11:42:38.476] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:71:0)
[11:42:38.476] Timer tick, asking Buildbucket for the build status
[11:42:38.538] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:38.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:72:0) after 1m0s
[11:43:38.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:72:0)
[11:43:38.812] Timer tick, asking Buildbucket for the build status
[11:43:38.912] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:38.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:73:0) after 1m0s
[11:44:38.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:73:0)
[11:44:38.934] Timer tick, asking Buildbucket for the build status
[11:44:38.994] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:38.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:74:0) after 1m0s
[11:45:39.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:74:0)
[11:45:39.017] Timer tick, asking Buildbucket for the build status
[11:45:39.095] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:39.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:75:0) after 1m0s
[11:46:39.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:75:0)
[11:46:39.125] Timer tick, asking Buildbucket for the build status
[11:46:39.194] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:39.194] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:76:0) after 1m0s
[11:47:39.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:76:0)
[11:47:39.328] Timer tick, asking Buildbucket for the build status
[11:47:39.393] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:39.393] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:77:0) after 1m0s
[11:48:39.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:77:0)
[11:48:39.423] Timer tick, asking Buildbucket for the build status
[11:48:39.499] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:39.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:78:0) after 1m0s
[11:49:39.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:78:0)
[11:49:39.547] Timer tick, asking Buildbucket for the build status
[11:49:39.763] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:49:39.763] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:79:0) after 1m0s
[11:50:39.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:79:0)
[11:50:39.868] Timer tick, asking Buildbucket for the build status
[11:50:40.019] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:50:40.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:80:0) after 1m0s
[11:51:40.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:80:0)
[11:51:40.050] Timer tick, asking Buildbucket for the build status
[11:51:40.177] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:51:40.177] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:81:0) after 1m0s
[11:52:40.199] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:81:0)
[11:52:40.199] Timer tick, asking Buildbucket for the build status
[11:52:40.398] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:52:40.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:82:0) after 1m0s
[11:53:40.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:82:0)
[11:53:40.422] Timer tick, asking Buildbucket for the build status
[11:53:40.485] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:53:40.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:83:0) after 1m0s
[11:54:40.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:83:0)
[11:54:40.524] Timer tick, asking Buildbucket for the build status
[11:54:40.653] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:54:40.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:84:0) after 1m0s
[11:55:40.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:84:0)
[11:55:40.675] Timer tick, asking Buildbucket for the build status
[11:55:40.768] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:55:40.768] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:85:0) after 1m0s
[11:56:40.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:85:0)
[11:56:40.789] Timer tick, asking Buildbucket for the build status
[11:56:40.844] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:56:40.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:86:0) after 1m0s
[11:57:40.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:86:0)
[11:57:40.884] Timer tick, asking Buildbucket for the build status
[11:57:41.113] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:57:41.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:87:0) after 1m0s
[11:58:41.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:87:0)
[11:58:41.137] Timer tick, asking Buildbucket for the build status
[11:58:41.217] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:58:41.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:88:0) after 1m0s
[11:59:41.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:88:0)
[11:59:41.240] Timer tick, asking Buildbucket for the build status
[11:59:41.279] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:59:41.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:89:0) after 1m0s
[12:00:41.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:89:0)
[12:00:41.308] Timer tick, asking Buildbucket for the build status
[12:00:41.375] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:00:41.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:90:0) after 1m0s
[12:01:41.399] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:90:0)
[12:01:41.399] Timer tick, asking Buildbucket for the build status
[12:01:41.469] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:01:41.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:91:0) after 1m0s
[12:02:41.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:91:0)
[12:02:41.493] Timer tick, asking Buildbucket for the build status
[12:02:41.613] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:02:41.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:92:0) after 1m0s
[12:03:41.640] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:92:0)
[12:03:41.640] Timer tick, asking Buildbucket for the build status
[12:03:41.836] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:03:41.836] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:93:0) after 1m0s
[12:04:41.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:93:0)
[12:04:41.872] Timer tick, asking Buildbucket for the build status
[12:04:41.933] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:41.933] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:94:0) after 1m0s
[12:05:42.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:94:0)
[12:05:42.022] Timer tick, asking Buildbucket for the build status
[12:05:42.268] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:42.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:95:0) after 1m0s
[12:06:42.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:95:0)
[12:06:42.337] Timer tick, asking Buildbucket for the build status
[12:06:42.399] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:42.399] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:96:0) after 1m0s
[12:07:42.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:96:0)
[12:07:42.438] Timer tick, asking Buildbucket for the build status
[12:07:42.548] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:42.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:97:0) after 1m0s
[12:08:42.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:97:0)
[12:08:42.582] Timer tick, asking Buildbucket for the build status
[12:08:42.745] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:42.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:98:0) after 1m0s
[12:09:42.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:98:0)
[12:09:42.771] Timer tick, asking Buildbucket for the build status
[12:09:42.845] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:42.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:99:0) after 1m0s
[12:10:42.874] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:99:0)
[12:10:42.874] Timer tick, asking Buildbucket for the build status
[12:10:42.971] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:42.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:100:0) after 1m0s
[12:11:43.004] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:100:0)
[12:11:43.004] Timer tick, asking Buildbucket for the build status
[12:11:43.068] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:43.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:101:0) after 1m0s
[12:12:43.100] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:101:0)
[12:12:43.100] Timer tick, asking Buildbucket for the build status
[12:12:43.349] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:43.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:102:0) after 1m0s
[12:13:43.375] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:102:0)
[12:13:43.375] Timer tick, asking Buildbucket for the build status
[12:13:44.067] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:44.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:103:0) after 1m0s
[12:14:44.109] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:103:0)
[12:14:44.109] Timer tick, asking Buildbucket for the build status
[12:14:44.203] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:44.203] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:104:0) after 1m0s
[12:15:44.231] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:104:0)
[12:15:44.231] Timer tick, asking Buildbucket for the build status
[12:15:44.327] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:44.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:105:0) after 1m0s
[12:16:44.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:105:0)
[12:16:44.348] Timer tick, asking Buildbucket for the build status
[12:16:44.535] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:44.535] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:106:0) after 1m0s
[12:17:44.571] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:106:0)
[12:17:44.571] Timer tick, asking Buildbucket for the build status
[12:17:44.642] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:44.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:107:0) after 1m0s
[12:18:44.671] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:107:0)
[12:18:44.671] Timer tick, asking Buildbucket for the build status
[12:18:44.754] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:44.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:108:0) after 1m0s
[12:19:44.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:108:0)
[12:19:44.793] Timer tick, asking Buildbucket for the build status
[12:19:44.934] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:44.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:109:0) after 1m0s
[12:20:44.980] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:109:0)
[12:20:44.980] Timer tick, asking Buildbucket for the build status
[12:20:45.042] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:45.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:110:0) after 1m0s
[12:21:45.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:110:0)
[12:21:45.066] Timer tick, asking Buildbucket for the build status
[12:21:45.126] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:45.126] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:111:0) after 1m0s
[12:22:45.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:111:0)
[12:22:45.155] Timer tick, asking Buildbucket for the build status
[12:22:45.271] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:45.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:112:0) after 1m0s
[12:23:45.353] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:112:0)
[12:23:45.353] Timer tick, asking Buildbucket for the build status
[12:23:45.448] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:45.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:113:0) after 1m0s
[12:24:45.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:113:0)
[12:24:45.542] Timer tick, asking Buildbucket for the build status
[12:24:45.705] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:45.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:114:0) after 1m0s
[12:25:45.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:114:0)
[12:25:45.732] Timer tick, asking Buildbucket for the build status
[12:25:45.874] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:45.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:115:0) after 1m0s
[12:26:46.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:115:0)
[12:26:46.285] Timer tick, asking Buildbucket for the build status
[12:26:46.429] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:46.429] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:116:0) after 1m0s
[12:27:46.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:116:0)
[12:27:46.454] Timer tick, asking Buildbucket for the build status
[12:27:46.531] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:46.531] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:117:0) after 1m0s
[12:28:46.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:117:0)
[12:28:46.573] Timer tick, asking Buildbucket for the build status
[12:28:46.652] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:46.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:118:0) after 1m0s
[12:29:46.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:118:0)
[12:29:46.679] Timer tick, asking Buildbucket for the build status
[12:29:46.740] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:46.740] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:119:0) after 1m0s
[12:30:46.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:119:0)
[12:30:46.766] Timer tick, asking Buildbucket for the build status
[12:30:46.974] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:46.974] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:120:0) after 1m0s
[12:31:47.001] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:120:0)
[12:31:47.001] Timer tick, asking Buildbucket for the build status
[12:31:47.149] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:47.149] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:121:0) after 1m0s
[12:32:47.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:121:0)
[12:32:47.175] Timer tick, asking Buildbucket for the build status
[12:32:47.357] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:47.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:122:0) after 1m0s
[12:33:47.404] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:122:0)
[12:33:47.404] Timer tick, asking Buildbucket for the build status
[12:33:47.558] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:47.558] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:123:0) after 1m0s
[12:34:47.616] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:123:0)
[12:34:47.616] Timer tick, asking Buildbucket for the build status
[12:34:47.675] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:47.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:124:0) after 1m0s
[12:35:47.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:124:0)
[12:35:47.701] Timer tick, asking Buildbucket for the build status
[12:35:48.088] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:48.088] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:125:0) after 1m0s
[12:36:48.115] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:125:0)
[12:36:48.115] Timer tick, asking Buildbucket for the build status
[12:36:48.282] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:48.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:126:0) after 1m0s
[12:37:48.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:126:0)
[12:37:48.311] Timer tick, asking Buildbucket for the build status
[12:37:48.509] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:48.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:127:0) after 1m0s
[12:38:48.559] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:127:0)
[12:38:48.559] Timer tick, asking Buildbucket for the build status
[12:38:48.671] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:48.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:128:0) after 1m0s
[12:39:48.695] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:128:0)
[12:39:48.695] Timer tick, asking Buildbucket for the build status
[12:39:48.804] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:48.804] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:129:0) after 1m0s
[12:40:48.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:129:0)
[12:40:48.848] Timer tick, asking Buildbucket for the build status
[12:40:48.920] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:48.920] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:130:0) after 1m0s
[12:41:48.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:130:0)
[12:41:48.946] Timer tick, asking Buildbucket for the build status
[12:41:49.017] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:49.017] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:131:0) after 1m0s
[12:42:49.041] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:131:0)
[12:42:49.041] Timer tick, asking Buildbucket for the build status
[12:42:49.134] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:49.134] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:132:0) after 1m0s
[12:43:49.175] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:132:0)
[12:43:49.175] Timer tick, asking Buildbucket for the build status
[12:43:49.246] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:49.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:133:0) after 1m0s
[12:44:49.275] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:133:0)
[12:44:49.275] Timer tick, asking Buildbucket for the build status
[12:44:49.339] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:49.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:134:0) after 1m0s
[12:45:49.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:134:0)
[12:45:49.366] Timer tick, asking Buildbucket for the build status
[12:45:49.556] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:49.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:135:0) after 1m0s
[12:46:49.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:135:0)
[12:46:49.582] Timer tick, asking Buildbucket for the build status
[12:46:49.671] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:49.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:136:0) after 1m0s
[12:47:49.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:136:0)
[12:47:49.730] Timer tick, asking Buildbucket for the build status
[12:47:49.969] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:49.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:137:0) after 1m0s
[12:48:50.030] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:137:0)
[12:48:50.030] Timer tick, asking Buildbucket for the build status
[12:48:50.113] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:50.113] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:138:0) after 1m0s
[12:49:50.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:138:0)
[12:49:50.139] Timer tick, asking Buildbucket for the build status
[12:49:50.242] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:50.242] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:139:0) after 1m0s
[12:50:50.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:139:0)
[12:50:50.392] Timer tick, asking Buildbucket for the build status
[12:50:50.463] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:50.463] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:140:0) after 1m0s
[12:51:50.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:140:0)
[12:51:50.489] Timer tick, asking Buildbucket for the build status
[12:51:50.549] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:50.549] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:141:0) after 1m0s
[12:52:50.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:141:0)
[12:52:50.574] Timer tick, asking Buildbucket for the build status
[12:52:50.894] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:50.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:142:0) after 1m0s
[12:53:50.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:142:0)
[12:53:50.939] Timer tick, asking Buildbucket for the build status
[12:53:51.043] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:51.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:143:0) after 1m0s
[12:54:51.064] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:143:0)
[12:54:51.064] Timer tick, asking Buildbucket for the build status
[12:54:51.185] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:51.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:144:0) after 1m0s
[12:55:51.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:144:0)
[12:55:51.213] Timer tick, asking Buildbucket for the build status
[12:55:51.275] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:51.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:145:0) after 1m0s
[12:56:51.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:145:0)
[12:56:51.309] Timer tick, asking Buildbucket for the build status
[12:56:51.501] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:51.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:146:0) after 1m0s
[12:57:51.574] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:146:0)
[12:57:51.574] Timer tick, asking Buildbucket for the build status
[12:57:51.658] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:51.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:147:0) after 1m0s
[12:58:51.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:147:0)
[12:58:51.682] Timer tick, asking Buildbucket for the build status
[12:58:51.746] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:51.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:148:0) after 1m0s
[12:59:51.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:148:0)
[12:59:51.774] Timer tick, asking Buildbucket for the build status
[12:59:51.855] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:51.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:149:0) after 1m0s
[13:00:51.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:149:0)
[13:00:51.915] Timer tick, asking Buildbucket for the build status
[13:00:51.981] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:51.981] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:150:0) after 1m0s
[13:01:52.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:150:0)
[13:01:52.007] Timer tick, asking Buildbucket for the build status
[13:01:52.620] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:52.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:151:0) after 1m0s
[13:02:52.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:151:0)
[13:02:52.663] Timer tick, asking Buildbucket for the build status
[13:02:52.777] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:52.777] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:152:0) after 1m0s
[13:03:52.801] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:152:0)
[13:03:52.801] Timer tick, asking Buildbucket for the build status
[13:03:52.941] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:52.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:153:0) after 1m0s
[13:04:53.020] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:153:0)
[13:04:53.020] Timer tick, asking Buildbucket for the build status
[13:04:53.289] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:53.289] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:154:0) after 1m0s
[13:05:53.324] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:154:0)
[13:05:53.324] Timer tick, asking Buildbucket for the build status
[13:05:53.398] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:53.398] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:155:0) after 1m0s
[13:06:53.430] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:155:0)
[13:06:53.430] Timer tick, asking Buildbucket for the build status
[13:06:53.529] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:53.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:156:0) after 1m0s
[13:07:53.557] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:156:0)
[13:07:53.557] Timer tick, asking Buildbucket for the build status
[13:07:53.632] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:53.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:157:0) after 1m0s
[13:08:53.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:157:0)
[13:08:53.659] Timer tick, asking Buildbucket for the build status
[13:08:53.901] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:53.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:158:0) after 1m0s
[13:09:53.928] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:158:0)
[13:09:53.928] Timer tick, asking Buildbucket for the build status
[13:09:53.986] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:53.986] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:159:0) after 1m0s
[13:10:54.022] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:159:0)
[13:10:54.022] Timer tick, asking Buildbucket for the build status
[13:10:54.109] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:54.109] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:160:0) after 1m0s
[13:11:54.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:160:0)
[13:11:54.138] Timer tick, asking Buildbucket for the build status
[13:11:54.206] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:54.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:161:0) after 1m0s
[13:12:54.278] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:161:0)
[13:12:54.278] Timer tick, asking Buildbucket for the build status
[13:12:54.381] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:54.381] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:162:0) after 1m0s
[13:13:54.409] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:162:0)
[13:13:54.410] Timer tick, asking Buildbucket for the build status
[13:13:54.500] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:54.500] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:163:0) after 1m0s
[13:14:54.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:163:0)
[13:14:54.525] Timer tick, asking Buildbucket for the build status
[13:14:54.573] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:54.573] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:164:0) after 1m0s
[13:15:54.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:164:0)
[13:15:54.600] Timer tick, asking Buildbucket for the build status
[13:15:54.783] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:54.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:165:0) after 1m0s
[13:16:54.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:165:0)
[13:16:54.952] Timer tick, asking Buildbucket for the build status
[13:16:55.053] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:55.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:166:0) after 1m0s
[13:17:55.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:166:0)
[13:17:55.135] Timer tick, asking Buildbucket for the build status
[13:17:55.221] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:55.221] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:167:0) after 1m0s
[13:18:55.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:167:0)
[13:18:55.247] Timer tick, asking Buildbucket for the build status
[13:18:55.360] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:55.360] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:168:0) after 1m0s
[13:19:55.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:168:0)
[13:19:55.396] Timer tick, asking Buildbucket for the build status
[13:19:55.451] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:55.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:169:0) after 1m0s
[13:20:55.482] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:169:0)
[13:20:55.482] Timer tick, asking Buildbucket for the build status
[13:20:55.640] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:55.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:170:0) after 1m0s
[13:21:55.773] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:170:0)
[13:21:55.773] Timer tick, asking Buildbucket for the build status
[13:21:55.876] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:55.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:171:0) after 1m0s
[13:22:55.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:171:0)
[13:22:55.979] Timer tick, asking Buildbucket for the build status
[13:22:56.033] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:56.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:172:0) after 1m0s
[13:23:56.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:172:0)
[13:23:56.061] Timer tick, asking Buildbucket for the build status
[13:23:56.208] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:56.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:173:0) after 1m0s
[13:24:56.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:173:0)
[13:24:56.373] Timer tick, asking Buildbucket for the build status
[13:24:56.499] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:56.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:174:0) after 1m0s
[13:25:56.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:174:0)
[13:25:56.539] Timer tick, asking Buildbucket for the build status
[13:25:56.631] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:56.631] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:175:0) after 1m0s
[13:26:56.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:175:0)
[13:26:56.762] Timer tick, asking Buildbucket for the build status
[13:26:56.852] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:56.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:176:0) after 1m0s
[13:27:56.885] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:176:0)
[13:27:56.885] Timer tick, asking Buildbucket for the build status
[13:27:56.980] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:56.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:177:0) after 1m0s
[13:28:57.008] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:177:0)
[13:28:57.008] Timer tick, asking Buildbucket for the build status
[13:28:57.123] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:57.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:178:0) after 1m0s
[13:29:57.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:178:0)
[13:29:57.151] Timer tick, asking Buildbucket for the build status
[13:29:57.256] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:57.256] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:179:0) after 1m0s
[13:30:57.298] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:179:0)
[13:30:57.298] Timer tick, asking Buildbucket for the build status
[13:30:57.386] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:57.386] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:180:0) after 1m0s
[13:31:57.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:180:0)
[13:31:57.425] Timer tick, asking Buildbucket for the build status
[13:31:57.499] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:57.499] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:181:0) after 1m0s
[13:32:57.549] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:181:0)
[13:32:57.549] Timer tick, asking Buildbucket for the build status
[13:32:57.609] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:57.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:182:0) after 1m0s
[13:33:57.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:182:0)
[13:33:57.643] Timer tick, asking Buildbucket for the build status
[13:33:57.722] Build 8931519354022785328: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:57.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9096949416182749152:183:0) after 1m0s
[13:34:28.625] Received PubSub notification, asking Buildbucket for the build status
[13:34:28.678] Build 8931519354022785328: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[13:34:28.678] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1540647267696692",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1540636407791593",
"failure_reason": "INFRA_FAILURE",
"id": "8931519354022785328",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"72.0.3593.0\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"\"}, \"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\": [\"3817-6aedaff\"], \"image\": [\"chrome-trusty-18091700-38cc06ee3ee\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-east1-b-h1jf\"], \"pool\": [\"luci.infra.cron\"], \"caches\": [\"vpython\"]}}, \"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1540636441799174",
"status": "COMPLETED",
"status_changed_ts": "1540647268244352",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9096949416182749152",
"scheduler_job_id:infra/Build From Tarball",
"swarming_dimension:builder:Build From Tarball",
"swarming_dimension:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_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:infra/cron",
"swarming_tag:buildbucket_build_id:8931519354022785328",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:c1e4e074f3337ff2dbbfb9aa3b0475a61adb09a6",
"swarming_tag:builder:Build From Tarball",
"swarming_tag:caches:builder_a26b12d8a59ca9041fe7666278599d55714efad5956e4752511581aec53352b0_v2",
"swarming_tag:cpu:x86-64",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8931519354022785328/+/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:9096949416182749152",
"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:d41fa26fabd353b1ddcf98207590a9e56d6dfcea",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:40cde781de4c4610",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1540647268245491",
"url": "https://ci.chromium.org/b/8931519354022785328",
"utcnow_ts": "1540647268660926"
}
}
[13:34:28.678] Invocation finished in 3h1m2.331053715s with status FAILED