[02:43:02.134] New invocation is queued and will start shortly
[02:43:02.134] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[02:43:03.381] Starting the invocation (attempt 1)
[02:43:03.412] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:43:03.412] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:43:03.412] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9098428562898524448",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"version\":\"70.0.3538.57\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9098428562898524448",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[02:43:04.230] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1539225783481948",
"id": "8932998500818521168",
"lease_expiration_ts": "1539344584091778",
"lease_key": "260029033",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"70.0.3538.57\"}}",
"project": "infra",
"result_details_json": "null",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1539225784096321",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9098428562898524448",
"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:8932998500818521168",
"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/8932998500818521168/+/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:9098428562898524448",
"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:02984296a6429d35f9eb152239263b748ed182ad",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4079d315fea9ee10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1539225784096457",
"url": "https://ci.chromium.org/p/infra/builds/b8932998500818521168",
"utcnow_ts": "1539225784213229"
}
}
[02:43:04.230] Task URL: https://ci.chromium.org/p/infra/builds/b8932998500818521168
[02:43:04.230] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:2:0) after 1m0s
[02:43:46.579] Received PubSub notification, asking Buildbucket for the build status
[02:43:46.666] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:04.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:2:0)
[02:44:04.249] Timer tick, asking Buildbucket for the build status
[02:44:04.408] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:04.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:4:0) after 1m0s
[02:45:04.465] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:4:0)
[02:45:04.465] Timer tick, asking Buildbucket for the build status
[02:45:04.554] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:04.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:5:0) after 1m0s
[02:46:04.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:5:0)
[02:46:04.582] Timer tick, asking Buildbucket for the build status
[02:46:04.637] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:04.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:6:0) after 1m0s
[02:47:04.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:6:0)
[02:47:04.723] Timer tick, asking Buildbucket for the build status
[02:47:04.852] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:04.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:7:0) after 1m0s
[02:48:04.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:7:0)
[02:48:04.886] Timer tick, asking Buildbucket for the build status
[02:48:04.927] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:04.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:8:0) after 1m0s
[02:49:04.946] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:8:0)
[02:49:04.946] Timer tick, asking Buildbucket for the build status
[02:49:05.006] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:05.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:9:0) after 1m0s
[02:50:05.039] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:9:0)
[02:50:05.039] Timer tick, asking Buildbucket for the build status
[02:50:05.098] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:05.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:10:0) after 1m0s
[02:51:05.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:10:0)
[02:51:05.114] Timer tick, asking Buildbucket for the build status
[02:51:05.370] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:05.370] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:11:0) after 1m0s
[02:52:05.385] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:11:0)
[02:52:05.385] Timer tick, asking Buildbucket for the build status
[02:52:05.461] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:05.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:12:0) after 1m0s
[02:53:05.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:12:0)
[02:53:05.485] Timer tick, asking Buildbucket for the build status
[02:53:05.676] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:05.676] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:13:0) after 1m0s
[02:54:05.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:13:0)
[02:54:05.698] Timer tick, asking Buildbucket for the build status
[02:54:05.756] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:05.756] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:14:0) after 1m0s
[02:55:05.780] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:14:0)
[02:55:05.780] Timer tick, asking Buildbucket for the build status
[02:55:05.855] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:05.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:15:0) after 1m0s
[02:56:05.873] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:15:0)
[02:56:05.873] Timer tick, asking Buildbucket for the build status
[02:56:05.957] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:05.957] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:16:0) after 1m0s
[02:57:05.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:16:0)
[02:57:05.979] Timer tick, asking Buildbucket for the build status
[02:57:06.025] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:06.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:17:0) after 1m0s
[02:58:06.094] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:17:0)
[02:58:06.094] Timer tick, asking Buildbucket for the build status
[02:58:06.335] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:06.335] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:18:0) after 1m0s
[02:59:06.426] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:18:0)
[02:59:06.426] Timer tick, asking Buildbucket for the build status
[02:59:06.483] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:06.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:19:0) after 1m0s
[03:00:06.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:19:0)
[03:00:06.523] Timer tick, asking Buildbucket for the build status
[03:00:06.557] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:06.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:20:0) after 1m0s
[03:01:06.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:20:0)
[03:01:06.588] Timer tick, asking Buildbucket for the build status
[03:01:06.673] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:06.673] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:21:0) after 1m0s
[03:02:06.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:21:0)
[03:02:06.691] Timer tick, asking Buildbucket for the build status
[03:02:06.736] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:06.736] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:22:0) after 1m0s
[03:03:06.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:22:0)
[03:03:06.763] Timer tick, asking Buildbucket for the build status
[03:03:06.818] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:06.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:23:0) after 1m0s
[03:04:06.843] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:23:0)
[03:04:06.843] Timer tick, asking Buildbucket for the build status
[03:04:06.936] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:06.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:24:0) after 1m0s
[03:05:06.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:24:0)
[03:05:06.960] Timer tick, asking Buildbucket for the build status
[03:05:07.119] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:07.119] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:25:0) after 1m0s
[03:06:07.137] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:25:0)
[03:06:07.137] Timer tick, asking Buildbucket for the build status
[03:06:07.220] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:07.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:26:0) after 1m0s
[03:07:07.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:26:0)
[03:07:07.237] Timer tick, asking Buildbucket for the build status
[03:07:07.339] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:07.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:27:0) after 1m0s
[03:08:07.365] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:27:0)
[03:08:07.365] Timer tick, asking Buildbucket for the build status
[03:08:07.414] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:07.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:28:0) after 1m0s
[03:09:07.436] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:28:0)
[03:09:07.436] Timer tick, asking Buildbucket for the build status
[03:09:07.649] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:07.649] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:29:0) after 1m0s
[03:10:07.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:29:0)
[03:10:07.668] Timer tick, asking Buildbucket for the build status
[03:10:07.722] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:07.722] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:30:0) after 1m0s
[03:11:07.744] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:30:0)
[03:11:07.744] Timer tick, asking Buildbucket for the build status
[03:11:07.866] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:07.867] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:31:0) after 1m0s
[03:12:07.887] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:31:0)
[03:12:07.887] Timer tick, asking Buildbucket for the build status
[03:12:07.963] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:07.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:32:0) after 1m0s
[03:13:08.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:32:0)
[03:13:08.065] Timer tick, asking Buildbucket for the build status
[03:13:08.187] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:08.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:33:0) after 1m0s
[03:14:08.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:33:0)
[03:14:08.205] Timer tick, asking Buildbucket for the build status
[03:14:08.247] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:08.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:34:0) after 1m0s
[03:15:08.268] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:34:0)
[03:15:08.268] Timer tick, asking Buildbucket for the build status
[03:15:08.410] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:08.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:35:0) after 1m0s
[03:16:08.539] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:35:0)
[03:16:08.539] Timer tick, asking Buildbucket for the build status
[03:16:08.606] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:08.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:36:0) after 1m0s
[03:17:08.624] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:36:0)
[03:17:08.624] Timer tick, asking Buildbucket for the build status
[03:17:08.758] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:08.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:37:0) after 1m0s
[03:18:08.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:37:0)
[03:18:08.806] Timer tick, asking Buildbucket for the build status
[03:18:08.997] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:08.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:38:0) after 1m0s
[03:19:09.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:38:0)
[03:19:09.016] Timer tick, asking Buildbucket for the build status
[03:19:09.074] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:09.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:39:0) after 1m0s
[03:20:09.097] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:39:0)
[03:20:09.097] Timer tick, asking Buildbucket for the build status
[03:20:09.168] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:09.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:40:0) after 1m0s
[03:21:09.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:40:0)
[03:21:09.192] Timer tick, asking Buildbucket for the build status
[03:21:09.377] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:09.377] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:41:0) after 1m0s
[03:22:09.407] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:41:0)
[03:22:09.407] Timer tick, asking Buildbucket for the build status
[03:22:09.485] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:09.485] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:42:0) after 1m0s
[03:23:09.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:42:0)
[03:23:09.550] Timer tick, asking Buildbucket for the build status
[03:23:09.652] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:09.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:43:0) after 1m0s
[03:24:09.670] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:43:0)
[03:24:09.670] Timer tick, asking Buildbucket for the build status
[03:24:09.765] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:09.765] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:44:0) after 1m0s
[03:25:09.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:44:0)
[03:25:09.788] Timer tick, asking Buildbucket for the build status
[03:25:09.821] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:09.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:45:0) after 1m0s
[03:26:09.870] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:45:0)
[03:26:09.870] Timer tick, asking Buildbucket for the build status
[03:26:10.010] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:10.010] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:46:0) after 1m0s
[03:27:10.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:46:0)
[03:27:10.117] Timer tick, asking Buildbucket for the build status
[03:27:10.207] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:10.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:47:0) after 1m0s
[03:28:10.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:47:0)
[03:28:10.236] Timer tick, asking Buildbucket for the build status
[03:28:10.298] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:10.298] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:48:0) after 1m0s
[03:29:10.358] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:48:0)
[03:29:10.358] Timer tick, asking Buildbucket for the build status
[03:29:10.440] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:10.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:49:0) after 1m0s
[03:30:10.520] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:49:0)
[03:30:10.520] Timer tick, asking Buildbucket for the build status
[03:30:10.653] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:10.653] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:50:0) after 1m0s
[03:31:10.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:50:0)
[03:31:10.693] Timer tick, asking Buildbucket for the build status
[03:31:10.825] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:10.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:51:0) after 1m0s
[03:32:10.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:51:0)
[03:32:10.855] Timer tick, asking Buildbucket for the build status
[03:32:10.945] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:10.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:52:0) after 1m0s
[03:33:10.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:52:0)
[03:33:10.971] Timer tick, asking Buildbucket for the build status
[03:33:11.124] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:11.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:53:0) after 1m0s
[03:34:11.144] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:53:0)
[03:34:11.144] Timer tick, asking Buildbucket for the build status
[03:34:11.220] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:11.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:54:0) after 1m0s
[03:35:11.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:54:0)
[03:35:11.246] Timer tick, asking Buildbucket for the build status
[03:35:11.314] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:11.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:55:0) after 1m0s
[03:36:11.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:55:0)
[03:36:11.331] Timer tick, asking Buildbucket for the build status
[03:36:11.395] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:11.396] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:56:0) after 1m0s
[03:37:11.419] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:56:0)
[03:37:11.419] Timer tick, asking Buildbucket for the build status
[03:37:11.517] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:11.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:57:0) after 1m0s
[03:38:11.565] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:57:0)
[03:38:11.565] Timer tick, asking Buildbucket for the build status
[03:38:11.671] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:11.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:58:0) after 1m0s
[03:39:11.740] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:58:0)
[03:39:11.740] Timer tick, asking Buildbucket for the build status
[03:39:11.913] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:11.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:59:0) after 1m0s
[03:40:11.932] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:59:0)
[03:40:11.932] Timer tick, asking Buildbucket for the build status
[03:40:11.978] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:11.978] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:60:0) after 1m0s
[03:41:11.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:60:0)
[03:41:11.998] Timer tick, asking Buildbucket for the build status
[03:41:12.057] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:12.057] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:61:0) after 1m0s
[03:42:12.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:61:0)
[03:42:12.084] Timer tick, asking Buildbucket for the build status
[03:42:12.159] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:12.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:62:0) after 1m0s
[03:43:12.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:62:0)
[03:43:12.246] Timer tick, asking Buildbucket for the build status
[03:43:12.323] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:12.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:63:0) after 1m0s
[03:44:12.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:63:0)
[03:44:12.346] Timer tick, asking Buildbucket for the build status
[03:44:12.424] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:12.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:64:0) after 1m0s
[03:45:12.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:64:0)
[03:45:12.451] Timer tick, asking Buildbucket for the build status
[03:45:12.637] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:12.637] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:65:0) after 1m0s
[03:46:12.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:65:0)
[03:46:12.685] Timer tick, asking Buildbucket for the build status
[03:46:12.751] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:12.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:66:0) after 1m0s
[03:47:12.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:66:0)
[03:47:12.822] Timer tick, asking Buildbucket for the build status
[03:47:12.899] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:12.899] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:67:0) after 1m0s
[03:48:12.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:67:0)
[03:48:12.932] Timer tick, asking Buildbucket for the build status
[03:48:12.996] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:12.996] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:68:0) after 1m0s
[03:49:13.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:68:0)
[03:49:13.017] Timer tick, asking Buildbucket for the build status
[03:49:13.101] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:13.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:69:0) after 1m0s
[03:50:13.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:69:0)
[03:50:13.131] Timer tick, asking Buildbucket for the build status
[03:50:13.236] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:13.236] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:70:0) after 1m0s
[03:51:13.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:70:0)
[03:51:13.256] Timer tick, asking Buildbucket for the build status
[03:51:13.325] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:13.325] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:71:0) after 1m0s
[03:52:13.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:71:0)
[03:52:13.346] Timer tick, asking Buildbucket for the build status
[03:52:13.498] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:13.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:72:0) after 1m0s
[03:53:13.541] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:72:0)
[03:53:13.541] Timer tick, asking Buildbucket for the build status
[03:53:13.597] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:13.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:73:0) after 1m0s
[03:54:13.620] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:73:0)
[03:54:13.620] Timer tick, asking Buildbucket for the build status
[03:54:13.701] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:13.701] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:74:0) after 1m0s
[03:55:13.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:74:0)
[03:55:13.731] Timer tick, asking Buildbucket for the build status
[03:55:13.852] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:13.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:75:0) after 1m0s
[03:56:13.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:75:0)
[03:56:13.871] Timer tick, asking Buildbucket for the build status
[03:56:13.944] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:13.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:76:0) after 1m0s
[03:57:13.964] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:76:0)
[03:57:13.964] Timer tick, asking Buildbucket for the build status
[03:57:14.058] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:14.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:77:0) after 1m0s
[03:58:14.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:77:0)
[03:58:14.082] Timer tick, asking Buildbucket for the build status
[03:58:14.165] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:14.165] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:78:0) after 1m0s
[03:59:14.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:78:0)
[03:59:14.210] Timer tick, asking Buildbucket for the build status
[03:59:14.271] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:14.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:79:0) after 1m0s
[04:00:14.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:79:0)
[04:00:14.328] Timer tick, asking Buildbucket for the build status
[04:00:14.380] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:14.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:80:0) after 1m0s
[04:01:14.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:80:0)
[04:01:14.436] Timer tick, asking Buildbucket for the build status
[04:01:14.538] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:14.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:81:0) after 1m0s
[04:02:14.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:81:0)
[04:02:14.595] Timer tick, asking Buildbucket for the build status
[04:02:14.764] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:14.764] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:82:0) after 1m0s
[04:03:14.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:82:0)
[04:03:14.787] Timer tick, asking Buildbucket for the build status
[04:03:14.858] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:14.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:83:0) after 1m0s
[04:04:14.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:83:0)
[04:04:14.882] Timer tick, asking Buildbucket for the build status
[04:04:14.960] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:14.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:84:0) after 1m0s
[04:05:14.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:84:0)
[04:05:14.986] Timer tick, asking Buildbucket for the build status
[04:05:15.039] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:15.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:85:0) after 1m0s
[04:06:15.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:85:0)
[04:06:15.139] Timer tick, asking Buildbucket for the build status
[04:06:15.220] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:15.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:86:0) after 1m0s
[04:07:15.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:86:0)
[04:07:15.310] Timer tick, asking Buildbucket for the build status
[04:07:15.362] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:15.362] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:87:0) after 1m0s
[04:08:15.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:87:0)
[04:08:15.386] Timer tick, asking Buildbucket for the build status
[04:08:15.451] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:15.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:88:0) after 1m0s
[04:09:15.474] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:88:0)
[04:09:15.474] Timer tick, asking Buildbucket for the build status
[04:09:15.606] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:15.606] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:89:0) after 1m0s
[04:10:15.632] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:89:0)
[04:10:15.632] Timer tick, asking Buildbucket for the build status
[04:10:16.021] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:16.021] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:90:0) after 1m0s
[04:11:16.066] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:90:0)
[04:11:16.066] Timer tick, asking Buildbucket for the build status
[04:11:16.133] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:16.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:91:0) after 1m0s
[04:12:16.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:91:0)
[04:12:16.200] Timer tick, asking Buildbucket for the build status
[04:12:16.239] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:16.239] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:92:0) after 1m0s
[04:13:16.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:92:0)
[04:13:16.287] Timer tick, asking Buildbucket for the build status
[04:13:16.406] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:16.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:93:0) after 1m0s
[04:14:16.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:93:0)
[04:14:16.444] Timer tick, asking Buildbucket for the build status
[04:14:16.564] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:16.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:94:0) after 1m0s
[04:15:16.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:94:0)
[04:15:16.587] Timer tick, asking Buildbucket for the build status
[04:15:16.621] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:16.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:95:0) after 1m0s
[04:16:16.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:95:0)
[04:16:16.663] Timer tick, asking Buildbucket for the build status
[04:16:16.726] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:16.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:96:0) after 1m0s
[04:17:16.750] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:96:0)
[04:17:16.750] Timer tick, asking Buildbucket for the build status
[04:17:16.816] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:16.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:97:0) after 1m0s
[04:18:16.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:97:0)
[04:18:16.841] Timer tick, asking Buildbucket for the build status
[04:18:16.927] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:16.927] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:98:0) after 1m0s
[04:19:16.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:98:0)
[04:19:16.952] Timer tick, asking Buildbucket for the build status
[04:19:17.045] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:17.045] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:99:0) after 1m0s
[04:20:17.067] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:99:0)
[04:20:17.067] Timer tick, asking Buildbucket for the build status
[04:20:17.127] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:17.127] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:100:0) after 1m0s
[04:21:17.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:100:0)
[04:21:17.150] Timer tick, asking Buildbucket for the build status
[04:21:17.210] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:17.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:101:0) after 1m0s
[04:22:17.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:101:0)
[04:22:17.246] Timer tick, asking Buildbucket for the build status
[04:22:17.308] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:17.308] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:102:0) after 1m0s
[04:23:17.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:102:0)
[04:23:17.344] Timer tick, asking Buildbucket for the build status
[04:23:17.400] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:17.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:103:0) after 1m0s
[04:24:17.425] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:103:0)
[04:24:17.425] Timer tick, asking Buildbucket for the build status
[04:24:17.473] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:17.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:104:0) after 1m0s
[04:25:17.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:104:0)
[04:25:17.493] Timer tick, asking Buildbucket for the build status
[04:25:17.548] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:17.548] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:105:0) after 1m0s
[04:26:17.576] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:105:0)
[04:26:17.576] Timer tick, asking Buildbucket for the build status
[04:26:17.652] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:17.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:106:0) after 1m0s
[04:27:17.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:106:0)
[04:27:17.674] Timer tick, asking Buildbucket for the build status
[04:27:17.761] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:17.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:107:0) after 1m0s
[04:28:17.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:107:0)
[04:28:17.793] Timer tick, asking Buildbucket for the build status
[04:28:17.849] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:17.849] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:108:0) after 1m0s
[04:29:17.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:108:0)
[04:29:17.872] Timer tick, asking Buildbucket for the build status
[04:29:17.941] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:17.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:109:0) after 1m0s
[04:30:17.963] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:109:0)
[04:30:17.963] Timer tick, asking Buildbucket for the build status
[04:30:18.031] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:18.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:110:0) after 1m0s
[04:31:18.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:110:0)
[04:31:18.065] Timer tick, asking Buildbucket for the build status
[04:31:18.121] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:18.121] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:111:0) after 1m0s
[04:32:18.148] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:111:0)
[04:32:18.148] Timer tick, asking Buildbucket for the build status
[04:32:18.196] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:18.197] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:112:0) after 1m0s
[04:33:18.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:112:0)
[04:33:18.237] Timer tick, asking Buildbucket for the build status
[04:33:18.307] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:18.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:113:0) after 1m0s
[04:34:18.347] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:113:0)
[04:34:18.347] Timer tick, asking Buildbucket for the build status
[04:34:18.392] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:18.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:114:0) after 1m0s
[04:35:18.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:114:0)
[04:35:18.423] Timer tick, asking Buildbucket for the build status
[04:35:18.560] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:18.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:115:0) after 1m0s
[04:36:18.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:115:0)
[04:36:18.585] Timer tick, asking Buildbucket for the build status
[04:36:18.623] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:18.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:116:0) after 1m0s
[04:37:18.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:116:0)
[04:37:18.756] Timer tick, asking Buildbucket for the build status
[04:37:18.850] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:18.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:117:0) after 1m0s
[04:38:18.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:117:0)
[04:38:18.869] Timer tick, asking Buildbucket for the build status
[04:38:18.905] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:18.905] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:118:0) after 1m0s
[04:39:18.931] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:118:0)
[04:39:18.931] Timer tick, asking Buildbucket for the build status
[04:39:18.988] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:18.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:119:0) after 1m0s
[04:40:19.007] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:119:0)
[04:40:19.007] Timer tick, asking Buildbucket for the build status
[04:40:19.146] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:19.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:120:0) after 1m0s
[04:41:19.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:120:0)
[04:41:19.167] Timer tick, asking Buildbucket for the build status
[04:41:19.414] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:19.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:121:0) after 1m0s
[04:42:19.435] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:121:0)
[04:42:19.435] Timer tick, asking Buildbucket for the build status
[04:42:19.497] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:19.498] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:122:0) after 1m0s
[04:43:19.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:122:0)
[04:43:19.519] Timer tick, asking Buildbucket for the build status
[04:43:19.647] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:19.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:123:0) after 1m0s
[04:44:19.668] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:123:0)
[04:44:19.668] Timer tick, asking Buildbucket for the build status
[04:44:19.758] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:19.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:124:0) after 1m0s
[04:45:19.795] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:124:0)
[04:45:19.795] Timer tick, asking Buildbucket for the build status
[04:45:19.844] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:19.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:125:0) after 1m0s
[04:46:19.876] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:125:0)
[04:46:19.876] Timer tick, asking Buildbucket for the build status
[04:46:19.946] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:19.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:126:0) after 1m0s
[04:47:19.978] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:126:0)
[04:47:19.978] Timer tick, asking Buildbucket for the build status
[04:47:20.050] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:20.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:127:0) after 1m0s
[04:48:20.084] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:127:0)
[04:48:20.084] Timer tick, asking Buildbucket for the build status
[04:48:20.120] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:20.120] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:128:0) after 1m0s
[04:49:20.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:128:0)
[04:49:20.141] Timer tick, asking Buildbucket for the build status
[04:49:20.207] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:20.207] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:129:0) after 1m0s
[04:50:20.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:129:0)
[04:50:20.234] Timer tick, asking Buildbucket for the build status
[04:50:20.268] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:20.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:130:0) after 1m0s
[04:51:20.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:130:0)
[04:51:20.346] Timer tick, asking Buildbucket for the build status
[04:51:20.427] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:20.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:131:0) after 1m0s
[04:52:20.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:131:0)
[04:52:20.461] Timer tick, asking Buildbucket for the build status
[04:52:20.650] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:20.650] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:132:0) after 1m0s
[04:53:20.680] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:132:0)
[04:53:20.680] Timer tick, asking Buildbucket for the build status
[04:53:20.732] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:20.732] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:133:0) after 1m0s
[04:54:20.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:133:0)
[04:54:20.756] Timer tick, asking Buildbucket for the build status
[04:54:20.817] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:20.817] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:134:0) after 1m0s
[04:55:20.954] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:134:0)
[04:55:20.954] Timer tick, asking Buildbucket for the build status
[04:55:21.031] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:21.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:135:0) after 1m0s
[04:56:21.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:135:0)
[04:56:21.133] Timer tick, asking Buildbucket for the build status
[04:56:21.168] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:21.168] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:136:0) after 1m0s
[04:57:21.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:136:0)
[04:57:21.188] Timer tick, asking Buildbucket for the build status
[04:57:21.224] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:21.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:137:0) after 1m0s
[04:58:21.257] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:137:0)
[04:58:21.257] Timer tick, asking Buildbucket for the build status
[04:58:21.410] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:21.410] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:138:0) after 1m0s
[04:59:21.432] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:138:0)
[04:59:21.432] Timer tick, asking Buildbucket for the build status
[04:59:21.473] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:21.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:139:0) after 1m0s
[05:00:21.510] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:139:0)
[05:00:21.510] Timer tick, asking Buildbucket for the build status
[05:00:21.616] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:21.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:140:0) after 1m0s
[05:01:21.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:140:0)
[05:01:21.643] Timer tick, asking Buildbucket for the build status
[05:01:21.683] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:21.683] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:141:0) after 1m0s
[05:02:21.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:141:0)
[05:02:21.711] Timer tick, asking Buildbucket for the build status
[05:02:21.815] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:21.815] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:142:0) after 1m0s
[05:03:21.847] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:142:0)
[05:03:21.847] Timer tick, asking Buildbucket for the build status
[05:03:22.128] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:22.128] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:143:0) after 1m0s
[05:04:22.156] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:143:0)
[05:04:22.156] Timer tick, asking Buildbucket for the build status
[05:04:22.218] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:22.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:144:0) after 1m0s
[05:05:22.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:144:0)
[05:05:22.243] Timer tick, asking Buildbucket for the build status
[05:05:22.383] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:22.384] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:145:0) after 1m0s
[05:06:22.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:145:0)
[05:06:22.459] Timer tick, asking Buildbucket for the build status
[05:06:22.509] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:22.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:146:0) after 1m0s
[05:07:22.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:146:0)
[05:07:22.540] Timer tick, asking Buildbucket for the build status
[05:07:22.630] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:22.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:147:0) after 1m0s
[05:08:22.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:147:0)
[05:08:22.664] Timer tick, asking Buildbucket for the build status
[05:08:22.725] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:22.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:148:0) after 1m0s
[05:09:22.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:148:0)
[05:09:22.757] Timer tick, asking Buildbucket for the build status
[05:09:22.863] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:22.863] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:149:0) after 1m0s
[05:10:23.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:149:0)
[05:10:23.035] Timer tick, asking Buildbucket for the build status
[05:10:23.271] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:23.271] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:150:0) after 1m0s
[05:11:23.317] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:150:0)
[05:11:23.317] Timer tick, asking Buildbucket for the build status
[05:11:23.455] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:23.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:151:0) after 1m0s
[05:12:23.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:151:0)
[05:12:23.479] Timer tick, asking Buildbucket for the build status
[05:12:23.536] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:23.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:152:0) after 1m0s
[05:13:23.567] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:152:0)
[05:13:23.567] Timer tick, asking Buildbucket for the build status
[05:13:23.640] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:23.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:153:0) after 1m0s
[05:14:23.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:153:0)
[05:14:23.669] Timer tick, asking Buildbucket for the build status
[05:14:23.761] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:23.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:154:0) after 1m0s
[05:15:23.798] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:154:0)
[05:15:23.798] Timer tick, asking Buildbucket for the build status
[05:15:23.906] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:23.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:155:0) after 1m0s
[05:16:24.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:155:0)
[05:16:24.033] Timer tick, asking Buildbucket for the build status
[05:16:24.144] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:24.144] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:156:0) after 1m0s
[05:17:24.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:156:0)
[05:17:24.186] Timer tick, asking Buildbucket for the build status
[05:17:24.935] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:24.935] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:157:0) after 1m0s
[05:18:24.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:157:0)
[05:18:24.959] Timer tick, asking Buildbucket for the build status
[05:18:25.075] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:25.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:158:0) after 1m0s
[05:19:25.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:158:0)
[05:19:25.102] Timer tick, asking Buildbucket for the build status
[05:19:25.199] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:25.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:159:0) after 1m0s
[05:20:25.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:159:0)
[05:20:25.221] Timer tick, asking Buildbucket for the build status
[05:20:25.296] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:25.296] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:160:0) after 1m0s
[05:21:25.411] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:160:0)
[05:21:25.411] Timer tick, asking Buildbucket for the build status
[05:21:25.479] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:25.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:161:0) after 1m0s
[05:22:25.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:161:0)
[05:22:25.602] Timer tick, asking Buildbucket for the build status
[05:22:25.798] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:25.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:162:0) after 1m0s
[05:23:25.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:162:0)
[05:23:25.830] Timer tick, asking Buildbucket for the build status
[05:23:25.913] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:25.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:163:0) after 1m0s
[05:24:25.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:163:0)
[05:24:25.945] Timer tick, asking Buildbucket for the build status
[05:24:26.023] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:26.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:164:0) after 1m0s
[05:25:26.046] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:164:0)
[05:25:26.046] Timer tick, asking Buildbucket for the build status
[05:25:26.087] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:26.087] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:165:0) after 1m0s
[05:26:26.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:165:0)
[05:26:26.183] Timer tick, asking Buildbucket for the build status
[05:26:26.241] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:26.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:166:0) after 1m0s
[05:27:26.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:166:0)
[05:27:26.354] Timer tick, asking Buildbucket for the build status
[05:27:26.439] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:26.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:167:0) after 1m0s
[05:28:26.537] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:167:0)
[05:28:26.537] Timer tick, asking Buildbucket for the build status
[05:28:26.638] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:26.638] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:168:0) after 1m0s
[05:29:26.726] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:168:0)
[05:29:26.726] Timer tick, asking Buildbucket for the build status
[05:29:26.805] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:26.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:169:0) after 1m0s
[05:30:26.877] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:169:0)
[05:30:26.877] Timer tick, asking Buildbucket for the build status
[05:30:27.059] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:27.059] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:170:0) after 1m0s
[05:31:27.080] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:170:0)
[05:31:27.080] Timer tick, asking Buildbucket for the build status
[05:31:27.158] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:27.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:171:0) after 1m0s
[05:32:27.242] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:171:0)
[05:32:27.242] Timer tick, asking Buildbucket for the build status
[05:32:27.356] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:27.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:172:0) after 1m0s
[05:33:27.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:172:0)
[05:33:27.383] Timer tick, asking Buildbucket for the build status
[05:33:27.599] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:27.599] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:173:0) after 1m0s
[05:34:27.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:173:0)
[05:34:27.621] Timer tick, asking Buildbucket for the build status
[05:34:27.679] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:27.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:174:0) after 1m0s
[05:35:27.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:174:0)
[05:35:27.698] Timer tick, asking Buildbucket for the build status
[05:35:27.771] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:27.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:175:0) after 1m0s
[05:36:27.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:175:0)
[05:36:27.794] Timer tick, asking Buildbucket for the build status
[05:36:27.875] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:27.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:176:0) after 1m0s
[05:37:27.957] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:176:0)
[05:37:27.957] Timer tick, asking Buildbucket for the build status
[05:37:28.033] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:28.033] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:177:0) after 1m0s
[05:38:28.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:177:0)
[05:38:28.135] Timer tick, asking Buildbucket for the build status
[05:38:28.217] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:28.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:178:0) after 1m0s
[05:39:28.250] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:178:0)
[05:39:28.250] Timer tick, asking Buildbucket for the build status
[05:39:28.397] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:28.397] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:179:0) after 1m0s
[05:40:28.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:179:0)
[05:40:28.424] Timer tick, asking Buildbucket for the build status
[05:40:28.503] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:28.503] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:180:0) after 1m0s
[05:41:28.531] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:180:0)
[05:41:28.531] Timer tick, asking Buildbucket for the build status
[05:41:28.572] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:28.572] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:181:0) after 1m0s
[05:42:28.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:181:0)
[05:42:28.648] Timer tick, asking Buildbucket for the build status
[05:42:28.743] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:28.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:182:0) after 1m0s
[05:43:28.779] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:182:0)
[05:43:28.779] Timer tick, asking Buildbucket for the build status
[05:43:28.846] Build 8932998500818521168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:28.846] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9098428562898524448:183:0) after 1m0s
[05:44:17.484] Received PubSub notification, asking Buildbucket for the build status
[05:44:17.589] Build 8932998500818521168: status "COMPLETED", result "FAILURE", failure_reason "INFRA_FAILURE", cancelation_reason ""
[05:44:17.589] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "AUTO",
"completed_ts": "1539236656425106",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1539225783481948",
"failure_reason": "INFRA_FAILURE",
"id": "8932998500818521168",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"version\": \"70.0.3538.57\"}}",
"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-east\", \"us-east1\", \"us-east1-b\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-14.04\"], \"id\": [\"gce-trusty-e833d7b0-us-east1-b-sc6z\"], \"pool\": [\"luci.infra.cron\"]}}, \"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1539225825661826",
"status": "COMPLETED",
"status_changed_ts": "1539236657029359",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9098428562898524448",
"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:8932998500818521168",
"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/8932998500818521168/+/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:9098428562898524448",
"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:02984296a6429d35f9eb152239263b748ed182ad",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:user_agent:recipe",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:4079d315fea9ee10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1539236657030318",
"url": "https://ci.chromium.org/p/infra/builds/b8932998500818521168",
"utcnow_ts": "1539236657536089"
}
}
[05:44:17.589] Invocation finished in 3h1m15.474739406s with status FAILED