[08:12:25.332] New invocation is queued and will start shortly
[08:12:25.332] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:12:27.021] Starting the invocation (attempt 1)
[08:12:27.104] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:12:27.104] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:12:27.104] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9054468310979006032",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"82.0.4052.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"c00491c5-b3c8-48af-90f0-45ee3932e2db\",\"title\":\"publish_tarball/0\"}]},\"version\":\"82.0.4052.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:9054468310979006032",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[08:12:27.546] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1581149547220944",
"id": "8889038248332311856",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4052.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c00491c5-b3c8-48af-90f0-45ee3932e2db\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4052.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1581149547400038",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9054468310979006032",
"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/8889038248332311856/+/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": "1581149547400143",
"url": "https://ci.chromium.org/b/8889038248332311856",
"utcnow_ts": "1581149547538312"
}
}
[08:12:27.546] Task URL: https://ci.chromium.org/b/8889038248332311856
[08:12:27.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:2:0) after 1m0s
[08:13:27.607] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:2:0)
[08:13:27.607] Timer tick, asking Buildbucket for the build status
[08:13:27.724] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:13:27.724] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:3:0) after 1m0s
[08:14:27.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:3:0)
[08:14:27.793] Timer tick, asking Buildbucket for the build status
[08:14:27.932] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:14:27.932] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:4:0) after 1m0s
[08:15:27.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:4:0)
[08:15:27.998] Timer tick, asking Buildbucket for the build status
[08:15:28.151] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:15:28.151] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:5:0) after 1m0s
[08:16:28.234] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:5:0)
[08:16:28.234] Timer tick, asking Buildbucket for the build status
[08:16:28.352] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:16:28.352] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:6:0) after 1m0s
[08:17:28.443] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:6:0)
[08:17:28.443] Timer tick, asking Buildbucket for the build status
[08:17:28.546] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:28.546] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:7:0) after 1m0s
[08:18:28.570] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:7:0)
[08:18:28.570] Timer tick, asking Buildbucket for the build status
[08:18:28.681] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:18:28.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:8:0) after 1m0s
[08:19:28.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:8:0)
[08:19:28.770] Timer tick, asking Buildbucket for the build status
[08:19:29.005] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:19:29.005] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:9:0) after 1m0s
[08:20:29.057] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:9:0)
[08:20:29.057] Timer tick, asking Buildbucket for the build status
[08:20:29.261] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:20:29.261] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:10:0) after 1m0s
[08:21:29.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:10:0)
[08:21:29.305] Timer tick, asking Buildbucket for the build status
[08:21:29.717] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:21:29.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:11:0) after 1m0s
[08:22:29.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:11:0)
[08:22:29.768] Timer tick, asking Buildbucket for the build status
[08:22:29.995] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:22:29.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:12:0) after 1m0s
[08:23:30.013] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:12:0)
[08:23:30.013] Timer tick, asking Buildbucket for the build status
[08:23:30.111] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:23:30.111] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:13:0) after 1m0s
[08:24:30.145] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:13:0)
[08:24:30.145] Timer tick, asking Buildbucket for the build status
[08:24:30.255] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:24:30.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:14:0) after 1m0s
[08:25:30.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:14:0)
[08:25:30.350] Timer tick, asking Buildbucket for the build status
[08:25:30.450] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:25:30.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:15:0) after 1m0s
[08:26:30.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:15:0)
[08:26:30.491] Timer tick, asking Buildbucket for the build status
[08:26:30.991] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:26:30.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:16:0) after 1m0s
[08:27:31.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:16:0)
[08:27:31.040] Timer tick, asking Buildbucket for the build status
[08:27:31.264] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:27:31.264] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:17:0) after 1m0s
[08:29:37.654] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:17:0)
[08:29:37.654] Timer tick, asking Buildbucket for the build status
[08:29:38.208] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:29:38.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:18:0) after 1m0s
[08:30:38.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:18:0)
[08:30:38.542] Timer tick, asking Buildbucket for the build status
[08:30:39.006] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:30:39.006] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:19:0) after 1m0s
[08:31:39.082] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:19:0)
[08:31:39.082] Timer tick, asking Buildbucket for the build status
[08:31:39.244] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:31:39.244] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:20:0) after 1m0s
[08:32:39.313] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:20:0)
[08:32:39.313] Timer tick, asking Buildbucket for the build status
[08:32:39.876] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:32:39.876] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:21:0) after 1m0s
[08:33:39.911] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:21:0)
[08:33:39.911] Timer tick, asking Buildbucket for the build status
[08:33:40.258] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:33:40.258] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:22:0) after 1m0s
[08:34:40.342] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:22:0)
[08:34:40.342] Timer tick, asking Buildbucket for the build status
[08:34:40.670] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:34:40.670] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:23:0) after 1m0s
[08:35:40.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:23:0)
[08:35:40.692] Timer tick, asking Buildbucket for the build status
[08:35:40.855] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:35:40.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:24:0) after 1m0s
[08:36:40.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:24:0)
[08:36:40.942] Timer tick, asking Buildbucket for the build status
[08:36:41.235] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:36:41.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:25:0) after 1m0s
[08:37:41.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:25:0)
[08:37:41.341] Timer tick, asking Buildbucket for the build status
[08:37:41.486] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:37:41.486] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:26:0) after 1m0s
[08:38:41.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:26:0)
[08:38:41.585] Timer tick, asking Buildbucket for the build status
[08:38:41.694] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:38:41.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:27:0) after 1m0s
[08:39:41.838] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:27:0)
[08:39:41.838] Timer tick, asking Buildbucket for the build status
[08:39:41.958] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:39:41.958] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:28:0) after 1m0s
[08:40:42.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:28:0)
[08:40:42.168] Timer tick, asking Buildbucket for the build status
[08:40:42.539] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:40:42.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:29:0) after 1m0s
[08:41:42.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:29:0)
[08:41:42.644] Timer tick, asking Buildbucket for the build status
[08:41:42.881] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:41:42.881] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:30:0) after 1m0s
[08:42:42.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:30:0)
[08:42:42.951] Timer tick, asking Buildbucket for the build status
[08:42:43.152] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:42:43.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:31:0) after 1m0s
[08:43:43.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:31:0)
[08:43:43.237] Timer tick, asking Buildbucket for the build status
[08:43:43.355] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:43:43.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:32:0) after 1m0s
[08:44:43.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:32:0)
[08:44:43.381] Timer tick, asking Buildbucket for the build status
[08:44:43.505] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:44:43.505] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:33:0) after 1m0s
[08:45:43.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:33:0)
[08:45:43.532] Timer tick, asking Buildbucket for the build status
[08:45:43.697] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:45:43.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:34:0) after 1m0s
[08:46:43.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:34:0)
[08:46:43.758] Timer tick, asking Buildbucket for the build status
[08:46:43.855] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:46:43.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:35:0) after 1m0s
[08:47:43.925] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:35:0)
[08:47:43.925] Timer tick, asking Buildbucket for the build status
[08:47:44.345] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:47:44.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:36:0) after 1m0s
[08:48:44.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:36:0)
[08:48:44.394] Timer tick, asking Buildbucket for the build status
[08:48:44.530] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:48:44.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:37:0) after 1m0s
[08:49:44.734] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:37:0)
[08:49:44.734] Timer tick, asking Buildbucket for the build status
[08:49:44.852] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:49:44.852] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:38:0) after 1m0s
[08:50:45.063] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:38:0)
[08:50:45.063] Timer tick, asking Buildbucket for the build status
[08:50:45.257] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:50:45.257] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:39:0) after 1m0s
[08:51:45.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:39:0)
[08:51:45.288] Timer tick, asking Buildbucket for the build status
[08:51:45.461] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:51:45.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:40:0) after 1m0s
[08:52:45.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:40:0)
[08:52:45.550] Timer tick, asking Buildbucket for the build status
[08:52:45.712] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:52:45.712] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:41:0) after 1m0s
[08:53:45.857] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:41:0)
[08:53:45.857] Timer tick, asking Buildbucket for the build status
[08:53:45.965] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:53:45.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:42:0) after 1m0s
[08:54:45.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:42:0)
[08:54:45.986] Timer tick, asking Buildbucket for the build status
[08:54:46.082] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:54:46.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:43:0) after 1m0s
[08:55:46.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:43:0)
[08:55:46.077] Timer tick, asking Buildbucket for the build status
[08:55:46.201] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:55:46.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:44:0) after 1m0s
[08:56:46.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:44:0)
[08:56:46.367] Timer tick, asking Buildbucket for the build status
[08:56:46.480] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:56:46.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:45:0) after 1m0s
[08:57:46.935] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:45:0)
[08:57:46.935] Timer tick, asking Buildbucket for the build status
[08:57:47.228] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:57:47.228] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:46:0) after 1m0s
[08:58:47.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:46:0)
[08:58:47.526] Timer tick, asking Buildbucket for the build status
[08:58:47.627] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:58:47.627] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:47:0) after 1m0s
[08:59:47.770] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:47:0)
[08:59:47.770] Timer tick, asking Buildbucket for the build status
[08:59:48.050] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:59:48.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:48:0) after 1m0s
[09:00:48.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:48:0)
[09:00:48.162] Timer tick, asking Buildbucket for the build status
[09:00:48.462] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:00:48.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:49:0) after 1m0s
[09:01:48.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:49:0)
[09:01:48.646] Timer tick, asking Buildbucket for the build status
[09:01:48.961] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:01:48.961] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:50:0) after 1m0s
[09:02:49.221] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:50:0)
[09:02:49.221] Timer tick, asking Buildbucket for the build status
[09:02:49.375] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:02:49.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:51:0) after 1m0s
[09:03:49.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:51:0)
[09:03:49.774] Timer tick, asking Buildbucket for the build status
[09:03:49.955] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:03:49.955] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:52:0) after 1m0s
[09:04:50.032] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:52:0)
[09:04:50.032] Timer tick, asking Buildbucket for the build status
[09:04:50.224] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:04:50.224] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:53:0) after 1m0s
[09:05:50.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:53:0)
[09:05:50.288] Timer tick, asking Buildbucket for the build status
[09:05:50.423] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:05:50.423] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:54:0) after 1m0s
[09:06:50.612] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:54:0)
[09:06:50.612] Timer tick, asking Buildbucket for the build status
[09:06:50.714] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:06:50.714] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:55:0) after 1m0s
[09:07:50.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:55:0)
[09:07:50.696] Timer tick, asking Buildbucket for the build status
[09:07:51.075] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:07:51.075] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:56:0) after 1m0s
[09:08:51.111] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:56:0)
[09:08:51.111] Timer tick, asking Buildbucket for the build status
[09:08:51.218] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:08:51.218] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:57:0) after 1m0s
[09:09:51.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:57:0)
[09:09:51.283] Timer tick, asking Buildbucket for the build status
[09:09:51.559] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:09:51.559] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:58:0) after 1m0s
[09:10:51.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:58:0)
[09:10:51.578] Timer tick, asking Buildbucket for the build status
[09:10:51.807] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:10:51.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:59:0) after 1m0s
[09:11:51.895] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:59:0)
[09:11:51.895] Timer tick, asking Buildbucket for the build status
[09:11:52.007] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:11:52.007] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:60:0) after 1m0s
[09:12:52.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:60:0)
[09:12:52.153] Timer tick, asking Buildbucket for the build status
[09:12:52.343] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:12:52.343] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:61:0) after 1m0s
[09:13:52.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:61:0)
[09:13:52.376] Timer tick, asking Buildbucket for the build status
[09:13:52.717] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:13:52.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:62:0) after 1m0s
[09:14:53.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:62:0)
[09:14:53.025] Timer tick, asking Buildbucket for the build status
[09:14:53.221] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:14:53.222] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:63:0) after 1m0s
[09:15:53.449] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:63:0)
[09:15:53.449] Timer tick, asking Buildbucket for the build status
[09:15:53.556] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:15:53.556] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:64:0) after 1m0s
[09:16:53.969] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:64:0)
[09:16:53.969] Timer tick, asking Buildbucket for the build status
[09:16:54.084] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:16:54.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:65:0) after 1m0s
[09:17:54.260] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:65:0)
[09:17:54.260] Timer tick, asking Buildbucket for the build status
[09:17:54.539] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:17:54.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:66:0) after 1m0s
[09:18:54.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:66:0)
[09:18:54.735] Timer tick, asking Buildbucket for the build status
[09:18:54.936] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:18:54.936] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:67:0) after 1m0s
[09:19:55.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:67:0)
[09:19:55.232] Timer tick, asking Buildbucket for the build status
[09:19:55.479] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:19:55.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:68:0) after 1m0s
[09:20:55.896] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:68:0)
[09:20:55.896] Timer tick, asking Buildbucket for the build status
[09:20:56.448] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:20:56.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:69:0) after 1m0s
[09:21:56.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:69:0)
[09:21:56.516] Timer tick, asking Buildbucket for the build status
[09:21:56.708] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:21:56.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:70:0) after 1m0s
[09:22:56.841] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:70:0)
[09:22:56.841] Timer tick, asking Buildbucket for the build status
[09:22:57.036] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:22:57.036] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:71:0) after 1m0s
[09:23:57.152] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:71:0)
[09:23:57.152] Timer tick, asking Buildbucket for the build status
[09:23:57.249] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:23:57.249] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:72:0) after 1m0s
[09:24:57.471] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:72:0)
[09:24:57.471] Timer tick, asking Buildbucket for the build status
[09:24:57.569] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:24:57.569] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:73:0) after 1m0s
[09:25:57.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:73:0)
[09:25:57.596] Timer tick, asking Buildbucket for the build status
[09:25:57.949] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:25:57.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:74:0) after 1m0s
[09:26:58.014] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:74:0)
[09:26:58.014] Timer tick, asking Buildbucket for the build status
[09:26:58.323] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:26:58.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:75:0) after 1m0s
[09:27:58.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:75:0)
[09:27:58.495] Timer tick, asking Buildbucket for the build status
[09:27:58.636] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:27:58.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:76:0) after 1m0s
[09:28:58.696] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:76:0)
[09:28:58.696] Timer tick, asking Buildbucket for the build status
[09:28:58.830] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:28:58.830] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:77:0) after 1m0s
[09:29:59.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:77:0)
[09:29:59.021] Timer tick, asking Buildbucket for the build status
[09:29:59.143] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:29:59.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:78:0) after 1m0s
[09:30:59.186] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:78:0)
[09:30:59.186] Timer tick, asking Buildbucket for the build status
[09:30:59.286] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:30:59.286] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:79:0) after 1m0s
[09:31:59.434] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:79:0)
[09:31:59.434] Timer tick, asking Buildbucket for the build status
[09:31:59.538] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:31:59.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:80:0) after 1m0s
[09:32:59.646] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:80:0)
[09:32:59.646] Timer tick, asking Buildbucket for the build status
[09:32:59.856] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:32:59.856] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:81:0) after 1m0s
[09:33:59.941] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:81:0)
[09:33:59.941] Timer tick, asking Buildbucket for the build status
[09:34:00.274] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:34:00.274] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:82:0) after 1m0s
[09:35:00.622] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:82:0)
[09:35:00.622] Timer tick, asking Buildbucket for the build status
[09:35:00.882] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:35:00.882] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:83:0) after 1m0s
[09:36:01.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:83:0)
[09:36:01.264] Timer tick, asking Buildbucket for the build status
[09:36:01.361] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:36:01.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:84:0) after 1m0s
[09:37:01.438] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:84:0)
[09:37:01.438] Timer tick, asking Buildbucket for the build status
[09:37:01.717] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:37:01.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:85:0) after 1m0s
[09:38:01.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:85:0)
[09:38:01.792] Timer tick, asking Buildbucket for the build status
[09:38:03.196] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:38:03.196] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:86:0) after 1m0s
[09:39:03.238] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:86:0)
[09:39:03.238] Timer tick, asking Buildbucket for the build status
[09:39:03.333] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:39:03.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:87:0) after 1m0s
[09:40:03.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:87:0)
[09:40:03.460] Timer tick, asking Buildbucket for the build status
[09:40:03.591] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:40:03.591] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:88:0) after 1m0s
[09:41:03.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:88:0)
[09:41:03.810] Timer tick, asking Buildbucket for the build status
[09:41:03.903] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:41:03.903] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:89:0) after 1m0s
[09:42:04.185] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:89:0)
[09:42:04.185] Timer tick, asking Buildbucket for the build status
[09:42:04.333] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:42:04.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:90:0) after 1m0s
[09:43:04.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:90:0)
[09:43:04.484] Timer tick, asking Buildbucket for the build status
[09:43:04.965] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:43:04.965] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:91:0) after 1m0s
[09:44:05.373] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:91:0)
[09:44:05.373] Timer tick, asking Buildbucket for the build status
[09:44:05.477] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:44:05.477] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:92:0) after 1m0s
[09:45:05.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:92:0)
[09:45:05.528] Timer tick, asking Buildbucket for the build status
[09:45:05.678] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:45:05.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:93:0) after 1m0s
[09:46:05.697] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:93:0)
[09:46:05.697] Timer tick, asking Buildbucket for the build status
[09:46:05.953] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:46:05.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:94:0) after 1m0s
[09:47:06.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:94:0)
[09:47:06.038] Timer tick, asking Buildbucket for the build status
[09:47:06.135] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:47:06.135] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:95:0) after 1m0s
[09:48:06.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:95:0)
[09:48:06.162] Timer tick, asking Buildbucket for the build status
[09:48:06.314] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:48:06.314] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:96:0) after 1m0s
[09:49:06.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:96:0)
[09:49:06.354] Timer tick, asking Buildbucket for the build status
[09:49:06.747] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:49:06.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:97:0) after 1m0s
[09:50:06.796] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:97:0)
[09:50:06.796] Timer tick, asking Buildbucket for the build status
[09:50:06.983] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:50:06.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:98:0) after 1m0s
[09:51:07.117] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:98:0)
[09:51:07.117] Timer tick, asking Buildbucket for the build status
[09:51:07.349] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:51:07.349] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:99:0) after 1m0s
[09:52:07.600] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:99:0)
[09:52:07.600] Timer tick, asking Buildbucket for the build status
[09:52:07.713] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:52:07.713] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:100:0) after 1m0s
[09:53:07.927] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:100:0)
[09:53:07.927] Timer tick, asking Buildbucket for the build status
[09:53:08.032] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:53:08.032] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:101:0) after 1m0s
[09:54:08.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:101:0)
[09:54:08.122] Timer tick, asking Buildbucket for the build status
[09:54:08.232] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:54:08.232] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:102:0) after 1m0s
[09:55:08.343] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:102:0)
[09:55:08.343] Timer tick, asking Buildbucket for the build status
[09:55:08.474] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:55:08.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:103:0) after 1m0s
[09:56:08.485] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:103:0)
[09:56:08.485] Timer tick, asking Buildbucket for the build status
[09:56:08.589] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:56:08.589] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:104:0) after 1m0s
[09:57:08.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:104:0)
[09:57:08.647] Timer tick, asking Buildbucket for the build status
[09:57:08.891] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:57:08.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:105:0) after 1m0s
[09:58:08.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:105:0)
[09:58:08.950] Timer tick, asking Buildbucket for the build status
[09:58:09.125] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:58:09.125] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:106:0) after 1m0s
[09:59:09.167] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:106:0)
[09:59:09.167] Timer tick, asking Buildbucket for the build status
[09:59:09.281] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[09:59:09.281] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:107:0) after 1m0s
[10:00:09.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:107:0)
[10:00:09.307] Timer tick, asking Buildbucket for the build status
[10:00:09.488] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:00:09.488] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:108:0) after 1m0s
[10:01:09.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:108:0)
[10:01:09.550] Timer tick, asking Buildbucket for the build status
[10:01:09.654] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:01:09.654] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:109:0) after 1m0s
[10:02:09.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:109:0)
[10:02:09.682] Timer tick, asking Buildbucket for the build status
[10:02:09.806] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:02:09.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:110:0) after 1m0s
[10:03:09.882] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:110:0)
[10:03:09.882] Timer tick, asking Buildbucket for the build status
[10:03:10.084] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:03:10.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:111:0) after 1m0s
[10:04:10.130] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:111:0)
[10:04:10.130] Timer tick, asking Buildbucket for the build status
[10:04:10.277] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:04:10.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:112:0) after 1m0s
[10:05:10.307] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:112:0)
[10:05:10.307] Timer tick, asking Buildbucket for the build status
[10:05:10.674] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:05:10.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:113:0) after 1m0s
[10:06:10.940] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:113:0)
[10:06:10.940] Timer tick, asking Buildbucket for the build status
[10:06:11.226] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:06:11.226] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:114:0) after 1m0s
[10:07:11.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:114:0)
[10:07:11.394] Timer tick, asking Buildbucket for the build status
[10:07:11.536] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:07:11.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:115:0) after 1m0s
[10:08:11.947] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:115:0)
[10:08:11.947] Timer tick, asking Buildbucket for the build status
[10:08:12.081] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:08:12.081] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:116:0) after 1m0s
[10:09:12.297] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:116:0)
[10:09:12.297] Timer tick, asking Buildbucket for the build status
[10:09:12.665] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:09:12.665] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:117:0) after 1m0s
[10:10:12.955] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:117:0)
[10:10:12.955] Timer tick, asking Buildbucket for the build status
[10:10:13.039] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:10:13.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:118:0) after 1m0s
[10:11:13.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:118:0)
[10:11:13.265] Timer tick, asking Buildbucket for the build status
[10:11:13.479] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:11:13.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:119:0) after 1m0s
[10:12:13.651] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:119:0)
[10:12:13.651] Timer tick, asking Buildbucket for the build status
[10:12:14.067] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:12:14.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:120:0) after 1m0s
[10:13:14.305] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:120:0)
[10:13:14.305] Timer tick, asking Buildbucket for the build status
[10:13:14.483] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:13:14.483] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:121:0) after 1m0s
[10:14:14.516] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:121:0)
[10:14:14.516] Timer tick, asking Buildbucket for the build status
[10:14:14.731] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:14:14.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:122:0) after 1m0s
[10:15:14.766] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:122:0)
[10:15:14.766] Timer tick, asking Buildbucket for the build status
[10:15:14.906] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:15:14.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:123:0) after 1m0s
[10:16:14.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:123:0)
[10:16:14.936] Timer tick, asking Buildbucket for the build status
[10:16:15.049] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:16:15.049] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:124:0) after 1m0s
[10:17:15.157] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:124:0)
[10:17:15.157] Timer tick, asking Buildbucket for the build status
[10:17:15.323] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:17:15.323] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:125:0) after 1m0s
[10:18:15.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:125:0)
[10:18:15.410] Timer tick, asking Buildbucket for the build status
[10:18:15.515] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:18:15.515] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:126:0) after 1m0s
[10:19:15.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:126:0)
[10:19:15.598] Timer tick, asking Buildbucket for the build status
[10:19:15.760] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:19:15.761] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:127:0) after 1m0s
[10:20:16.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:127:0)
[10:20:16.146] Timer tick, asking Buildbucket for the build status
[10:20:16.246] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:20:16.246] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:128:0) after 1m0s
[10:21:16.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:128:0)
[10:21:16.533] Timer tick, asking Buildbucket for the build status
[10:21:16.773] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:21:16.773] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:129:0) after 1m0s
[10:22:17.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:129:0)
[10:22:17.831] Timer tick, asking Buildbucket for the build status
[10:22:17.988] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:22:17.988] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:130:0) after 1m0s
[10:23:18.099] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:130:0)
[10:23:18.099] Timer tick, asking Buildbucket for the build status
[10:23:18.253] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:23:18.253] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:131:0) after 1m0s
[10:24:18.376] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:131:0)
[10:24:18.376] Timer tick, asking Buildbucket for the build status
[10:24:18.583] Build 8889038248332311856: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[10:24:18.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:132:0) after 1m0s
[10:24:24.665] Received PubSub notification, asking Buildbucket for the build status
[10:24:24.790] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:18.621] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:132:0)
[10:25:18.621] Timer tick, asking Buildbucket for the build status
[10:25:18.787] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:18.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:134:0) after 1m0s
[10:26:18.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:134:0)
[10:26:18.864] Timer tick, asking Buildbucket for the build status
[10:26:18.962] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:18.962] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:135:0) after 1m0s
[10:27:19.044] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:135:0)
[10:27:19.044] Timer tick, asking Buildbucket for the build status
[10:27:19.152] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:19.152] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:136:0) after 1m0s
[10:28:19.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:136:0)
[10:28:19.270] Timer tick, asking Buildbucket for the build status
[10:28:19.395] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:19.395] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:137:0) after 1m0s
[10:29:19.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:137:0)
[10:29:19.511] Timer tick, asking Buildbucket for the build status
[10:29:19.651] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:19.651] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:138:0) after 1m0s
[10:30:19.714] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:138:0)
[10:30:19.714] Timer tick, asking Buildbucket for the build status
[10:30:19.844] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:19.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:139:0) after 1m0s
[10:31:19.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:139:0)
[10:31:19.903] Timer tick, asking Buildbucket for the build status
[10:31:20.176] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:20.176] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:140:0) after 1m0s
[10:32:20.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:140:0)
[10:32:20.180] Timer tick, asking Buildbucket for the build status
[10:32:20.835] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:20.835] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:141:0) after 1m0s
[10:33:20.905] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:141:0)
[10:33:20.905] Timer tick, asking Buildbucket for the build status
[10:33:21.018] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:21.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:142:0) after 1m0s
[10:34:21.092] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:142:0)
[10:34:21.092] Timer tick, asking Buildbucket for the build status
[10:34:22.174] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:22.174] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:143:0) after 1m0s
[10:35:22.212] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:143:0)
[10:35:22.212] Timer tick, asking Buildbucket for the build status
[10:35:22.671] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:22.671] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:144:0) after 1m0s
[10:36:22.752] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:144:0)
[10:36:22.752] Timer tick, asking Buildbucket for the build status
[10:36:23.164] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:23.164] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:145:0) after 1m0s
[10:37:23.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:145:0)
[10:37:23.188] Timer tick, asking Buildbucket for the build status
[10:37:23.293] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:23.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:146:0) after 1m0s
[10:38:23.356] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:146:0)
[10:38:23.356] Timer tick, asking Buildbucket for the build status
[10:38:23.496] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:23.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:147:0) after 1m0s
[10:39:23.553] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:147:0)
[10:39:23.553] Timer tick, asking Buildbucket for the build status
[10:39:23.691] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:23.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:148:0) after 1m0s
[10:40:23.735] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:148:0)
[10:40:23.735] Timer tick, asking Buildbucket for the build status
[10:40:23.918] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:23.918] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:149:0) after 1m0s
[10:41:24.017] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:149:0)
[10:41:24.017] Timer tick, asking Buildbucket for the build status
[10:41:24.124] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:24.124] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:150:0) after 1m0s
[10:42:24.270] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:150:0)
[10:42:24.270] Timer tick, asking Buildbucket for the build status
[10:42:24.400] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:24.400] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:151:0) after 1m0s
[10:43:24.595] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:151:0)
[10:43:24.595] Timer tick, asking Buildbucket for the build status
[10:43:24.759] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:24.759] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:152:0) after 1m0s
[10:44:25.018] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:152:0)
[10:44:25.018] Timer tick, asking Buildbucket for the build status
[10:44:25.133] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:25.133] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:153:0) after 1m0s
[10:45:25.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:153:0)
[10:45:25.162] Timer tick, asking Buildbucket for the build status
[10:45:25.501] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:25.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:154:0) after 1m0s
[10:46:25.825] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:154:0)
[10:46:25.825] Timer tick, asking Buildbucket for the build status
[10:46:26.365] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:26.366] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:155:0) after 1m0s
[10:47:26.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:155:0)
[10:47:26.511] Timer tick, asking Buildbucket for the build status
[10:47:26.636] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:26.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:156:0) after 1m0s
[10:48:27.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:156:0)
[10:48:27.165] Timer tick, asking Buildbucket for the build status
[10:48:27.560] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:27.560] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:157:0) after 1m0s
[10:49:27.953] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:157:0)
[10:49:27.953] Timer tick, asking Buildbucket for the build status
[10:49:28.089] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:28.089] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:158:0) after 1m0s
[10:50:28.394] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:158:0)
[10:50:28.394] Timer tick, asking Buildbucket for the build status
[10:50:28.874] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:28.875] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:159:0) after 1m0s
[10:51:29.045] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:159:0)
[10:51:29.045] Timer tick, asking Buildbucket for the build status
[10:51:29.153] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:29.154] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:160:0) after 1m0s
[10:52:29.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:160:0)
[10:52:29.183] Timer tick, asking Buildbucket for the build status
[10:52:29.469] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:29.469] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:161:0) after 1m0s
[10:53:29.626] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:161:0)
[10:53:29.626] Timer tick, asking Buildbucket for the build status
[10:53:29.894] Build 8889038248332311856: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:29.894] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9054468310979006032:162:0) after 1m0s
[10:53:52.048] Received PubSub notification, asking Buildbucket for the build status
[10:53:53.727] Build 8889038248332311856: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[10:53:53.727] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1581159231351715",
"created_by": "project:infra",
"created_ts": "1581149547220944",
"failure_reason": "BUILD_FAILURE",
"id": "8889038248332311856",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"82.0.4052.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c00491c5-b3c8-48af-90f0-45ee3932e2db\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"82.0.4052.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\": \"82.0.4052.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"c00491c5-b3c8-48af-90f0-45ee3932e2db\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-ikzi\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"82.0.4052.0\"}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"git\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-cron-xenial-large-0-ikzi\"], \"image\": [\"chrome-xenial-20012600-8e1a7e485b7\"], \"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\": [\"4809-bb2013c\"], \"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": "1581157463536960",
"status": "COMPLETED",
"status_changed_ts": "1581157464097234",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9054468310979006032",
"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/8889038248332311856/+/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:4a3cacf5346e0b10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1581159231614826",
"url": "https://ci.chromium.org/b/8889038248332311856",
"utcnow_ts": "1581159233699719"
}
}
[10:53:53.727] Invocation finished in 2h41m28.414812024s with status FAILED