[10:59:56.696] New invocation is queued and will start shortly
[10:59:56.696] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[10:59:58.003] Starting the invocation (attempt 1)
[10:59:58.045] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:59:58.045] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:59:58.046] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9068047316318961264",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"79.0.3909.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"43993ee5-254e-4b87-ba70-157faf741d4d\",\"title\":\"publish_tarball/0\"}]},\"version\":\"79.0.3909.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:9068047316318961264",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[10:59:58.552] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1568199598168995",
"id": "8902617254109371840",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3909.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"43993ee5-254e-4b87-ba70-157faf741d4d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3909.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1568199598390850",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9068047316318961264",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8902617254109371840/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1568199598390971",
"url": "https://ci.chromium.org/b/8902617254109371840",
"utcnow_ts": "1568199598544270"
}
}
[10:59:58.552] Task URL: https://ci.chromium.org/b/8902617254109371840
[10:59:58.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:2:0) after 1m0s
[11:00:58.582] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:2:0)
[11:00:58.582] Timer tick, asking Buildbucket for the build status
[11:00:58.716] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:00:58.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:3:0) after 1m0s
[11:01:58.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:3:0)
[11:01:58.934] Timer tick, asking Buildbucket for the build status
[11:01:59.069] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:01:59.069] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:4:0) after 1m0s
[11:02:59.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:4:0)
[11:02:59.129] Timer tick, asking Buildbucket for the build status
[11:02:59.461] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:02:59.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:5:0) after 1m0s
[11:03:59.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:5:0)
[11:03:59.599] Timer tick, asking Buildbucket for the build status
[11:03:59.857] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:03:59.857] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:6:0) after 1m0s
[11:04:59.894] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:6:0)
[11:04:59.894] Timer tick, asking Buildbucket for the build status
[11:05:00.417] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:05:00.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:7:0) after 1m0s
[11:06:00.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:7:0)
[11:06:00.443] Timer tick, asking Buildbucket for the build status
[11:06:00.615] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:06:00.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:8:0) after 1m0s
[11:07:00.799] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:8:0)
[11:07:00.799] Timer tick, asking Buildbucket for the build status
[11:07:01.301] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:07:01.301] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:9:0) after 1m0s
[11:08:01.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:9:0)
[11:08:01.401] Timer tick, asking Buildbucket for the build status
[11:08:01.615] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:08:01.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:10:0) after 1m0s
[11:09:01.704] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:10:0)
[11:09:01.704] Timer tick, asking Buildbucket for the build status
[11:09:01.859] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:09:01.859] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:11:0) after 1m0s
[11:10:01.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:11:0)
[11:10:01.865] Timer tick, asking Buildbucket for the build status
[11:10:03.096] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:10:03.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:12:0) after 1m0s
[11:11:03.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:12:0)
[11:11:03.312] Timer tick, asking Buildbucket for the build status
[11:11:03.529] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:11:03.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:13:0) after 1m0s
[11:12:03.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:13:0)
[11:12:03.584] Timer tick, asking Buildbucket for the build status
[11:12:03.904] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:12:03.904] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:14:0) after 1m0s
[11:13:03.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:14:0)
[11:13:03.956] Timer tick, asking Buildbucket for the build status
[11:13:04.074] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:13:04.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:15:0) after 1m0s
[11:14:04.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:15:0)
[11:14:04.146] Timer tick, asking Buildbucket for the build status
[11:14:04.279] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:14:04.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:16:0) after 1m0s
[11:15:04.332] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:16:0)
[11:15:04.332] Timer tick, asking Buildbucket for the build status
[11:15:04.439] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:15:04.439] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:17:0) after 1m0s
[11:16:04.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:17:0)
[11:16:04.513] Timer tick, asking Buildbucket for the build status
[11:16:04.698] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:16:04.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:18:0) after 1m0s
[11:17:04.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:18:0)
[11:17:04.719] Timer tick, asking Buildbucket for the build status
[11:17:05.098] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:17:05.098] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:19:0) after 1m0s
[11:18:05.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:19:0)
[11:18:05.116] Timer tick, asking Buildbucket for the build status
[11:18:05.305] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:18:05.305] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:20:0) after 1m0s
[11:19:05.364] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:20:0)
[11:19:05.364] Timer tick, asking Buildbucket for the build status
[11:19:05.488] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:19:05.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:21:0) after 1m0s
[11:20:05.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:21:0)
[11:20:05.543] Timer tick, asking Buildbucket for the build status
[11:20:05.646] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:20:05.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:22:0) after 1m0s
[11:21:05.661] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:22:0)
[11:21:05.661] Timer tick, asking Buildbucket for the build status
[11:21:05.946] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:21:05.946] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:23:0) after 1m0s
[11:22:05.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:23:0)
[11:22:05.965] Timer tick, asking Buildbucket for the build status
[11:22:06.147] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:22:06.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:24:0) after 1m0s
[11:23:06.504] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:24:0)
[11:23:06.505] Timer tick, asking Buildbucket for the build status
[11:23:06.731] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:23:06.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:25:0) after 1m0s
[11:24:06.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:25:0)
[11:24:06.903] Timer tick, asking Buildbucket for the build status
[11:24:07.014] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:24:07.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:26:0) after 1m0s
[11:25:07.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:26:0)
[11:25:07.168] Timer tick, asking Buildbucket for the build status
[11:25:07.424] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:25:07.424] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:27:0) after 1m0s
[11:26:07.572] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:27:0)
[11:26:07.572] Timer tick, asking Buildbucket for the build status
[11:26:07.784] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:26:07.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:28:0) after 1m0s
[11:27:07.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:28:0)
[11:27:07.898] Timer tick, asking Buildbucket for the build status
[11:27:08.320] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:27:08.320] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:29:0) after 1m0s
[11:28:08.371] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:29:0)
[11:28:08.371] Timer tick, asking Buildbucket for the build status
[11:28:08.534] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:28:08.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:30:0) after 1m0s
[11:29:08.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:30:0)
[11:29:08.563] Timer tick, asking Buildbucket for the build status
[11:29:09.158] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:29:09.158] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:31:0) after 1m0s
[11:30:09.204] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:31:0)
[11:30:09.204] Timer tick, asking Buildbucket for the build status
[11:30:09.748] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:30:09.748] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:32:0) after 1m0s
[11:31:09.762] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:32:0)
[11:31:09.763] Timer tick, asking Buildbucket for the build status
[11:31:10.421] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:31:10.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:33:0) after 1m0s
[11:32:10.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:33:0)
[11:32:10.509] Timer tick, asking Buildbucket for the build status
[11:32:10.674] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:32:10.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:34:0) after 1m0s
[11:33:10.849] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:34:0)
[11:33:10.849] Timer tick, asking Buildbucket for the build status
[11:33:11.345] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:33:11.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:35:0) after 1m0s
[11:34:11.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:35:0)
[11:34:11.372] Timer tick, asking Buildbucket for the build status
[11:34:11.712] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:34:11.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:36:0) after 1m0s
[11:35:11.912] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:36:0)
[11:35:11.912] Timer tick, asking Buildbucket for the build status
[11:35:12.152] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:35:12.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:37:0) after 1m0s
[11:36:12.141] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:37:0)
[11:36:12.141] Timer tick, asking Buildbucket for the build status
[11:36:12.608] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:36:12.608] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:38:0) after 1m0s
[11:37:12.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:38:0)
[11:37:12.649] Timer tick, asking Buildbucket for the build status
[11:37:12.940] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:37:12.940] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:39:0) after 1m0s
[11:38:12.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:39:0)
[11:38:12.950] Timer tick, asking Buildbucket for the build status
[11:38:13.257] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:38:13.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:40:0) after 1m0s
[11:39:13.243] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:40:0)
[11:39:13.243] Timer tick, asking Buildbucket for the build status
[11:39:13.392] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:39:13.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:41:0) after 1m0s
[11:40:13.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:41:0)
[11:40:13.496] Timer tick, asking Buildbucket for the build status
[11:40:14.529] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:40:14.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:42:0) after 1m0s
[11:41:14.558] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:42:0)
[11:41:14.558] Timer tick, asking Buildbucket for the build status
[11:41:14.787] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:41:14.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:43:0) after 1m0s
[11:42:14.812] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:43:0)
[11:42:14.812] Timer tick, asking Buildbucket for the build status
[11:42:14.913] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:42:14.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:44:0) after 1m0s
[11:43:14.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:44:0)
[11:43:14.970] Timer tick, asking Buildbucket for the build status
[11:43:15.341] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:43:15.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:45:0) after 1m0s
[11:44:15.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:45:0)
[11:44:15.401] Timer tick, asking Buildbucket for the build status
[11:44:15.603] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:44:15.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:46:0) after 1m0s
[11:45:15.633] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:46:0)
[11:45:15.633] Timer tick, asking Buildbucket for the build status
[11:45:15.751] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:45:15.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:47:0) after 1m0s
[11:46:15.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:47:0)
[11:46:15.777] Timer tick, asking Buildbucket for the build status
[11:46:15.919] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:46:15.919] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:48:0) after 1m0s
[11:47:15.983] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:48:0)
[11:47:15.983] Timer tick, asking Buildbucket for the build status
[11:47:16.107] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:47:16.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:49:0) after 1m0s
[11:48:16.112] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:49:0)
[11:48:16.112] Timer tick, asking Buildbucket for the build status
[11:48:18.074] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:48:18.074] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:50:0) after 1m0s
[11:49:18.136] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:50:0)
[11:49:18.136] Timer tick, asking Buildbucket for the build status
[11:49:18.420] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:49:18.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:51:0) after 1m0s
[11:50:18.498] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:51:0)
[11:50:18.498] Timer tick, asking Buildbucket for the build status
[11:50:18.625] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:50:18.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:52:0) after 1m0s
[11:51:18.687] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:52:0)
[11:51:18.687] Timer tick, asking Buildbucket for the build status
[11:51:18.862] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:51:18.862] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:53:0) after 1m0s
[11:52:18.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:53:0)
[11:52:18.903] Timer tick, asking Buildbucket for the build status
[11:52:19.058] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:52:19.058] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:54:0) after 1m0s
[11:53:19.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:54:0)
[11:53:19.088] Timer tick, asking Buildbucket for the build status
[11:53:19.359] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:53:19.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:55:0) after 1m0s
[11:54:19.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:55:0)
[11:54:19.396] Timer tick, asking Buildbucket for the build status
[11:54:19.552] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:54:19.552] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:56:0) after 1m0s
[11:55:19.648] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:56:0)
[11:55:19.648] Timer tick, asking Buildbucket for the build status
[11:55:20.385] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:55:20.385] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:57:0) after 1m0s
[11:56:20.588] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:57:0)
[11:56:20.588] Timer tick, asking Buildbucket for the build status
[11:56:20.725] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:56:20.725] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:58:0) after 1m0s
[11:57:20.852] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:58:0)
[11:57:20.852] Timer tick, asking Buildbucket for the build status
[11:57:21.019] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:57:21.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:59:0) after 1m0s
[11:58:21.241] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:59:0)
[11:58:21.241] Timer tick, asking Buildbucket for the build status
[11:58:21.579] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:58:21.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:60:0) after 1m0s
[11:59:21.727] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:60:0)
[11:59:21.727] Timer tick, asking Buildbucket for the build status
[11:59:21.825] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[11:59:21.825] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:61:0) after 1m0s
[12:00:21.865] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:61:0)
[12:00:21.865] Timer tick, asking Buildbucket for the build status
[12:00:21.975] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:00:21.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:62:0) after 1m0s
[12:01:22.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:62:0)
[12:01:22.042] Timer tick, asking Buildbucket for the build status
[12:01:22.266] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:01:22.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:63:0) after 1m0s
[12:02:22.269] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:63:0)
[12:02:22.269] Timer tick, asking Buildbucket for the build status
[12:02:22.425] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:02:22.425] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:64:0) after 1m0s
[12:03:22.454] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:64:0)
[12:03:22.454] Timer tick, asking Buildbucket for the build status
[12:03:22.615] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:03:22.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:65:0) after 1m0s
[12:04:22.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:65:0)
[12:04:22.723] Timer tick, asking Buildbucket for the build status
[12:04:22.902] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:04:22.902] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:66:0) after 1m0s
[12:05:22.918] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:66:0)
[12:05:22.918] Timer tick, asking Buildbucket for the build status
[12:05:23.147] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:05:23.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:67:0) after 1m0s
[12:06:23.178] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:67:0)
[12:06:23.178] Timer tick, asking Buildbucket for the build status
[12:06:23.456] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:06:23.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:68:0) after 1m0s
[12:07:23.512] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:68:0)
[12:07:23.512] Timer tick, asking Buildbucket for the build status
[12:07:23.798] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:07:23.798] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:69:0) after 1m0s
[12:08:23.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:69:0)
[12:08:23.923] Timer tick, asking Buildbucket for the build status
[12:08:24.171] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:08:24.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:70:0) after 1m0s
[12:09:24.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:70:0)
[12:09:24.271] Timer tick, asking Buildbucket for the build status
[12:09:24.442] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:09:24.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:71:0) after 1m0s
[12:10:24.606] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:71:0)
[12:10:24.606] Timer tick, asking Buildbucket for the build status
[12:10:24.858] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:10:24.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:72:0) after 1m0s
[12:11:24.884] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:72:0)
[12:11:24.884] Timer tick, asking Buildbucket for the build status
[12:11:25.131] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:11:25.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:73:0) after 1m0s
[12:12:25.172] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:73:0)
[12:12:25.172] Timer tick, asking Buildbucket for the build status
[12:12:25.288] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:12:25.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:74:0) after 1m0s
[12:13:25.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:74:0)
[12:13:25.313] Timer tick, asking Buildbucket for the build status
[12:13:25.561] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:13:25.561] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:75:0) after 1m0s
[12:14:25.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:75:0)
[12:14:25.631] Timer tick, asking Buildbucket for the build status
[12:14:25.809] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:14:25.809] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:76:0) after 1m0s
[12:15:25.888] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:76:0)
[12:15:25.888] Timer tick, asking Buildbucket for the build status
[12:15:26.273] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:15:26.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:77:0) after 1m0s
[12:16:26.308] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:77:0)
[12:16:26.308] Timer tick, asking Buildbucket for the build status
[12:16:26.706] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:16:26.706] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:78:0) after 1m0s
[12:17:26.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:78:0)
[12:17:26.730] Timer tick, asking Buildbucket for the build status
[12:17:26.931] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:17:26.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:79:0) after 1m0s
[12:18:27.056] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:79:0)
[12:18:27.056] Timer tick, asking Buildbucket for the build status
[12:18:27.244] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:18:27.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:80:0) after 1m0s
[12:19:27.310] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:80:0)
[12:19:27.310] Timer tick, asking Buildbucket for the build status
[12:19:27.437] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:19:27.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:81:0) after 1m0s
[12:20:27.529] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:81:0)
[12:20:27.529] Timer tick, asking Buildbucket for the build status
[12:20:27.945] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:20:27.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:82:0) after 1m0s
[12:21:27.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:82:0)
[12:21:27.968] Timer tick, asking Buildbucket for the build status
[12:21:28.225] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:21:28.225] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:83:0) after 1m0s
[12:22:28.285] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:83:0)
[12:22:28.285] Timer tick, asking Buildbucket for the build status
[12:22:29.280] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:22:29.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:84:0) after 1m0s
[12:23:29.392] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:84:0)
[12:23:29.392] Timer tick, asking Buildbucket for the build status
[12:23:29.716] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:23:29.716] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:85:0) after 1m0s
[12:24:29.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:85:0)
[12:24:29.984] Timer tick, asking Buildbucket for the build status
[12:24:30.135] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:24:30.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:86:0) after 1m0s
[12:25:30.187] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:86:0)
[12:25:30.187] Timer tick, asking Buildbucket for the build status
[12:25:30.724] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:25:30.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:87:0) after 1m0s
[12:26:30.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:87:0)
[12:26:30.722] Timer tick, asking Buildbucket for the build status
[12:26:30.880] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:26:30.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:88:0) after 1m0s
[12:27:30.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:88:0)
[12:27:30.922] Timer tick, asking Buildbucket for the build status
[12:27:31.299] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:27:31.299] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:89:0) after 1m0s
[12:28:31.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:89:0)
[12:28:31.342] Timer tick, asking Buildbucket for the build status
[12:28:31.730] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:28:31.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:90:0) after 1m0s
[12:29:31.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:90:0)
[12:29:31.806] Timer tick, asking Buildbucket for the build status
[12:29:31.998] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:29:31.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:91:0) after 1m0s
[12:30:32.009] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:91:0)
[12:30:32.009] Timer tick, asking Buildbucket for the build status
[12:30:32.131] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:30:32.131] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:92:0) after 1m0s
[12:31:32.174] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:92:0)
[12:31:32.174] Timer tick, asking Buildbucket for the build status
[12:31:32.332] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:31:32.332] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:93:0) after 1m0s
[12:32:32.486] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:93:0)
[12:32:32.486] Timer tick, asking Buildbucket for the build status
[12:32:32.600] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:32:32.600] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:94:0) after 1m0s
[12:33:32.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:94:0)
[12:33:32.623] Timer tick, asking Buildbucket for the build status
[12:33:33.211] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:33:33.211] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:95:0) after 1m0s
[12:34:33.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:95:0)
[12:34:33.238] Timer tick, asking Buildbucket for the build status
[12:34:33.518] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:34:33.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:96:0) after 1m0s
[12:35:33.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:96:0)
[12:35:33.578] Timer tick, asking Buildbucket for the build status
[12:35:33.828] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:35:33.828] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:97:0) after 1m0s
[12:36:33.862] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:97:0)
[12:36:33.862] Timer tick, asking Buildbucket for the build status
[12:36:34.051] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:36:34.051] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:98:0) after 1m0s
[12:37:34.138] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:98:0)
[12:37:34.138] Timer tick, asking Buildbucket for the build status
[12:37:34.279] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:37:34.279] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:99:0) after 1m0s
[12:38:34.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:99:0)
[12:38:34.346] Timer tick, asking Buildbucket for the build status
[12:38:34.583] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:38:34.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:100:0) after 1m0s
[12:39:34.906] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:100:0)
[12:39:34.906] Timer tick, asking Buildbucket for the build status
[12:39:35.259] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:39:35.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:101:0) after 1m0s
[12:40:35.306] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:101:0)
[12:40:35.306] Timer tick, asking Buildbucket for the build status
[12:40:35.537] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:40:35.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:102:0) after 1m0s
[12:41:35.573] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:102:0)
[12:41:35.573] Timer tick, asking Buildbucket for the build status
[12:41:35.758] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:41:35.758] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:103:0) after 1m0s
[12:42:35.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:103:0)
[12:42:35.927] Timer tick, asking Buildbucket for the build status
[12:42:36.821] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:42:36.821] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:104:0) after 1m0s
[12:43:36.858] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:104:0)
[12:43:36.858] Timer tick, asking Buildbucket for the build status
[12:43:37.002] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:43:37.002] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:105:0) after 1m0s
[12:44:37.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:105:0)
[12:44:37.126] Timer tick, asking Buildbucket for the build status
[12:44:37.265] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:44:37.265] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:106:0) after 1m0s
[12:45:37.296] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:106:0)
[12:45:37.296] Timer tick, asking Buildbucket for the build status
[12:45:37.415] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:45:37.415] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:107:0) after 1m0s
[12:46:37.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:107:0)
[12:46:37.444] Timer tick, asking Buildbucket for the build status
[12:46:37.613] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:46:37.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:108:0) after 1m0s
[12:47:37.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:108:0)
[12:47:37.647] Timer tick, asking Buildbucket for the build status
[12:47:38.286] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:47:38.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:109:0) after 1m0s
[12:48:38.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:109:0)
[12:48:38.431] Timer tick, asking Buildbucket for the build status
[12:48:38.582] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:48:38.582] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:110:0) after 1m0s
[12:49:38.611] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:110:0)
[12:49:38.611] Timer tick, asking Buildbucket for the build status
[12:49:38.720] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:49:38.720] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:111:0) after 1m0s
[12:50:38.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:111:0)
[12:50:38.837] Timer tick, asking Buildbucket for the build status
[12:50:38.982] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:50:38.982] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:112:0) after 1m0s
[12:51:39.085] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:112:0)
[12:51:39.085] Timer tick, asking Buildbucket for the build status
[12:51:39.455] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:51:39.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:113:0) after 1m0s
[12:52:39.599] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:113:0)
[12:52:39.599] Timer tick, asking Buildbucket for the build status
[12:52:40.112] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:52:40.112] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:114:0) after 1m0s
[12:53:40.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:114:0)
[12:53:40.183] Timer tick, asking Buildbucket for the build status
[12:53:40.356] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:53:40.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:115:0) after 1m0s
[12:54:40.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:115:0)
[12:54:40.443] Timer tick, asking Buildbucket for the build status
[12:54:40.581] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:54:40.581] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:116:0) after 1m0s
[12:55:40.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:116:0)
[12:55:40.677] Timer tick, asking Buildbucket for the build status
[12:55:41.826] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:55:41.826] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:117:0) after 1m0s
[12:56:41.938] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:117:0)
[12:56:41.938] Timer tick, asking Buildbucket for the build status
[12:56:42.209] Build 8902617254109371840: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[12:56:42.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:118:0) after 1m0s
[12:57:19.351] Received PubSub notification, asking Buildbucket for the build status
[12:57:19.459] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:42.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:118:0)
[12:57:42.277] Timer tick, asking Buildbucket for the build status
[12:57:42.389] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:42.389] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:120:0) after 1m0s
[12:58:42.535] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:120:0)
[12:58:42.535] Timer tick, asking Buildbucket for the build status
[12:58:43.913] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:43.913] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:121:0) after 1m0s
[12:59:44.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:121:0)
[12:59:44.007] Timer tick, asking Buildbucket for the build status
[12:59:44.185] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:44.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:122:0) after 1m0s
[13:00:44.463] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:122:0)
[13:00:44.463] Timer tick, asking Buildbucket for the build status
[13:00:44.623] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:44.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:123:0) after 1m0s
[13:01:44.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:123:0)
[13:01:44.735] Timer tick, asking Buildbucket for the build status
[13:01:45.858] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:45.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:124:0) after 1m0s
[13:02:46.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:124:0)
[13:02:46.035] Timer tick, asking Buildbucket for the build status
[13:02:46.326] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:46.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:125:0) after 1m0s
[13:03:46.363] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:125:0)
[13:03:46.363] Timer tick, asking Buildbucket for the build status
[13:03:46.689] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:46.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:126:0) after 1m0s
[13:04:46.699] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:126:0)
[13:04:46.699] Timer tick, asking Buildbucket for the build status
[13:04:47.161] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:47.161] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:127:0) after 1m0s
[13:05:47.240] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:127:0)
[13:05:47.240] Timer tick, asking Buildbucket for the build status
[13:05:47.524] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:47.524] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:128:0) after 1m0s
[13:06:47.524] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:128:0)
[13:06:47.524] Timer tick, asking Buildbucket for the build status
[13:06:47.658] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:47.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:129:0) after 1m0s
[13:07:47.643] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:129:0)
[13:07:47.643] Timer tick, asking Buildbucket for the build status
[13:07:47.829] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:47.829] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:130:0) after 1m0s
[13:08:47.919] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:130:0)
[13:08:47.919] Timer tick, asking Buildbucket for the build status
[13:08:48.550] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:48.550] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:131:0) after 1m0s
[13:09:48.603] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:131:0)
[13:09:48.603] Timer tick, asking Buildbucket for the build status
[13:09:48.746] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:48.746] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:132:0) after 1m0s
[13:10:48.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:132:0)
[13:10:48.838] Timer tick, asking Buildbucket for the build status
[13:10:49.243] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:49.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:133:0) after 1m0s
[13:11:49.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:133:0)
[13:11:49.368] Timer tick, asking Buildbucket for the build status
[13:11:49.592] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:49.593] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:134:0) after 1m0s
[13:12:49.675] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:134:0)
[13:12:49.675] Timer tick, asking Buildbucket for the build status
[13:12:49.928] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:49.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:135:0) after 1m0s
[13:13:50.052] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:135:0)
[13:13:50.052] Timer tick, asking Buildbucket for the build status
[13:13:50.219] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:50.219] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:136:0) after 1m0s
[13:14:50.302] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:136:0)
[13:14:50.302] Timer tick, asking Buildbucket for the build status
[13:14:50.664] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:50.664] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:137:0) after 1m0s
[13:15:50.791] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:137:0)
[13:15:50.791] Timer tick, asking Buildbucket for the build status
[13:15:50.911] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:50.911] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:138:0) after 1m0s
[13:16:51.071] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:138:0)
[13:16:51.071] Timer tick, asking Buildbucket for the build status
[13:16:51.274] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:51.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:139:0) after 1m0s
[13:17:51.321] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:139:0)
[13:17:51.321] Timer tick, asking Buildbucket for the build status
[13:17:51.502] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:51.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:140:0) after 1m0s
[13:18:52.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:140:0)
[13:18:52.081] Timer tick, asking Buildbucket for the build status
[13:18:53.585] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:53.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:141:0) after 1m0s
[13:19:53.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:141:0)
[13:19:53.626] Timer tick, asking Buildbucket for the build status
[13:19:53.773] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:53.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:142:0) after 1m0s
[13:20:53.879] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:142:0)
[13:20:53.879] Timer tick, asking Buildbucket for the build status
[13:20:54.047] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:54.047] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:143:0) after 1m0s
[13:21:54.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:143:0)
[13:21:54.265] Timer tick, asking Buildbucket for the build status
[13:21:54.450] Build 8902617254109371840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:54.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9068047316318961264:144:0) after 1m0s
[13:22:26.137] Received PubSub notification, asking Buildbucket for the build status
[13:22:26.248] Build 8902617254109371840: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[13:22:26.248] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1568208145253109",
"created_by": "project:infra",
"created_ts": "1568199598168995",
"failure_reason": "BUILD_FAILURE",
"id": "8902617254109371840",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3909.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"43993ee5-254e-4b87-ba70-157faf741d4d\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3909.0\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Download nodejs.') (retcode: 1)\"}, \"properties\": {\"$gatekeeper\": {\"group\": \"chromium.infra.cron\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3909.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"43993ee5-254e-4b87-ba70-157faf741d4d\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-b9kt\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"79.0.3909.0\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-b9kt\"], \"image\": [\"chrome-xenial-19052201-9cb74617499\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-32\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"luci.infra.cron\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4423-0229127\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Download nodejs.') (retcode: 1)\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1568206637739743",
"status": "COMPLETED",
"status_changed_ts": "1568206638667619",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9068047316318961264",
"scheduler_job_id:infra/Build From Tarball",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8902617254109371840/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:build_from_tarball",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/infra/infra",
"swarming_task_id:4738cc6beb3f8a10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1568208145317587",
"url": "https://ci.chromium.org/b/8902617254109371840",
"utcnow_ts": "1568208146238126"
}
}
[13:22:26.248] Invocation finished in 2h22m29.570809538s with status FAILED