[22:40:40.116] New invocation is queued and will start shortly
[22:40:40.116] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:40:41.365] Starting the invocation (attempt 1)
[22:40:41.459] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:40:41.459] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:40:41.459] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9056497415862793600",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"79.0.3945.130\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"5a55f8f3-4a3b-41ad-ab64-d071f1cff7ed\",\"title\":\"publish_tarball/0\"}]},\"version\":\"79.0.3945.130\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9056497415862793600",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[22:40:42.116] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1579214441645098",
"id": "8891067353595882256",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3945.130\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5a55f8f3-4a3b-41ad-ab64-d071f1cff7ed\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3945.130\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1579214441870072",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9056497415862793600",
"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/8891067353595882256/+/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": "1579214441870226",
"url": "https://ci.chromium.org/b/8891067353595882256",
"utcnow_ts": "1579214442100453"
}
}
[22:40:42.116] Task URL: https://ci.chromium.org/b/8891067353595882256
[22:40:42.116] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:2:0) after 1m0s
[22:41:42.244] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:2:0)
[22:41:42.244] Timer tick, asking Buildbucket for the build status
[22:41:42.512] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:41:42.512] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:3:0) after 1m0s
[22:42:42.677] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:3:0)
[22:42:42.677] Timer tick, asking Buildbucket for the build status
[22:42:42.792] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:42:42.792] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:4:0) after 1m0s
[22:43:42.942] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:4:0)
[22:43:42.942] Timer tick, asking Buildbucket for the build status
[22:43:43.053] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:43:43.053] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:5:0) after 1m0s
[22:44:43.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:5:0)
[22:44:43.077] Timer tick, asking Buildbucket for the build status
[22:44:43.520] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:44:43.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:6:0) after 1m0s
[22:45:43.542] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:6:0)
[22:45:43.542] Timer tick, asking Buildbucket for the build status
[22:45:43.647] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:45:43.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:7:0) after 1m0s
[22:46:43.691] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:7:0)
[22:46:43.691] Timer tick, asking Buildbucket for the build status
[22:46:44.155] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:46:44.155] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:8:0) after 1m0s
[22:47:44.191] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:8:0)
[22:47:44.191] Timer tick, asking Buildbucket for the build status
[22:47:44.358] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:47:44.358] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:9:0) after 1m0s
[22:48:44.386] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:9:0)
[22:48:44.386] Timer tick, asking Buildbucket for the build status
[22:48:44.614] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:48:44.614] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:10:0) after 1m0s
[22:49:44.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:10:0)
[22:49:44.722] Timer tick, asking Buildbucket for the build status
[22:49:44.916] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:49:44.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:11:0) after 1m0s
[22:50:44.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:11:0)
[22:50:44.973] Timer tick, asking Buildbucket for the build status
[22:50:45.266] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:50:45.266] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:12:0) after 1m0s
[22:51:45.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:12:0)
[22:51:45.383] Timer tick, asking Buildbucket for the build status
[22:51:45.640] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:51:45.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:13:0) after 1m0s
[22:52:45.645] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:13:0)
[22:52:45.645] Timer tick, asking Buildbucket for the build status
[22:52:45.874] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:52:45.874] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:14:0) after 1m0s
[22:53:45.923] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:14:0)
[22:53:45.923] Timer tick, asking Buildbucket for the build status
[22:53:46.084] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:53:46.084] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:15:0) after 1m0s
[22:54:46.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:15:0)
[22:54:46.095] Timer tick, asking Buildbucket for the build status
[22:54:46.234] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:54:46.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:16:0) after 1m0s
[22:55:46.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:16:0)
[22:55:46.965] Timer tick, asking Buildbucket for the build status
[22:55:47.082] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:55:47.082] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:17:0) after 1m0s
[22:56:47.416] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:17:0)
[22:56:47.416] Timer tick, asking Buildbucket for the build status
[22:56:47.739] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:56:47.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:18:0) after 1m0s
[22:57:49.180] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:18:0)
[22:57:49.180] Timer tick, asking Buildbucket for the build status
[22:57:50.064] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:57:50.064] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:19:0) after 1m0s
[22:58:50.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:19:0)
[22:58:50.288] Timer tick, asking Buildbucket for the build status
[22:58:50.432] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:58:50.432] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:20:0) after 1m0s
[22:59:50.637] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:20:0)
[22:59:50.637] Timer tick, asking Buildbucket for the build status
[22:59:51.099] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[22:59:51.099] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:21:0) after 1m0s
[23:00:51.290] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:21:0)
[23:00:51.290] Timer tick, asking Buildbucket for the build status
[23:00:51.595] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:00:51.595] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:22:0) after 1m0s
[23:01:51.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:22:0)
[23:01:51.890] Timer tick, asking Buildbucket for the build status
[23:01:52.183] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:01:52.183] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:23:0) after 1m0s
[23:02:52.245] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:23:0)
[23:02:52.245] Timer tick, asking Buildbucket for the build status
[23:02:53.392] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:02:53.392] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:24:0) after 1m0s
[23:03:53.578] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:24:0)
[23:03:53.578] Timer tick, asking Buildbucket for the build status
[23:03:53.723] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:03:53.723] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:25:0) after 1m0s
[23:04:54.050] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:25:0)
[23:04:54.050] Timer tick, asking Buildbucket for the build status
[23:04:54.290] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:04:54.290] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:26:0) after 1m0s
[23:05:54.523] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:26:0)
[23:05:54.523] Timer tick, asking Buildbucket for the build status
[23:05:54.695] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:05:54.695] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:27:0) after 1m0s
[23:06:54.922] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:27:0)
[23:06:54.922] Timer tick, asking Buildbucket for the build status
[23:06:55.122] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:06:55.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:28:0) after 1m0s
[23:07:55.440] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:28:0)
[23:07:55.440] Timer tick, asking Buildbucket for the build status
[23:07:55.617] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:07:55.617] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:29:0) after 1m0s
[23:08:55.807] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:29:0)
[23:08:55.807] Timer tick, asking Buildbucket for the build status
[23:08:55.949] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:08:55.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:30:0) after 1m0s
[23:09:56.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:30:0)
[23:09:56.171] Timer tick, asking Buildbucket for the build status
[23:09:56.461] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:09:56.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:31:0) after 1m0s
[23:10:56.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:31:0)
[23:10:56.598] Timer tick, asking Buildbucket for the build status
[23:10:56.879] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:10:56.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:32:0) after 1m0s
[23:11:57.042] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:32:0)
[23:11:57.042] Timer tick, asking Buildbucket for the build status
[23:11:57.361] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:11:57.361] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:33:0) after 1m0s
[23:12:57.525] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:33:0)
[23:12:57.525] Timer tick, asking Buildbucket for the build status
[23:12:57.641] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:12:57.641] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:34:0) after 1m0s
[23:13:57.689] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:34:0)
[23:13:57.689] Timer tick, asking Buildbucket for the build status
[23:13:57.795] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:13:57.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:35:0) after 1m0s
[23:14:57.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:35:0)
[23:14:57.871] Timer tick, asking Buildbucket for the build status
[23:14:58.012] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:14:58.012] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:36:0) after 1m0s
[23:15:58.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:36:0)
[23:15:58.006] Timer tick, asking Buildbucket for the build status
[23:15:58.157] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:15:58.157] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:37:0) after 1m0s
[23:16:58.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:37:0)
[23:16:58.210] Timer tick, asking Buildbucket for the build status
[23:16:58.351] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:58.351] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:38:0) after 1m0s
[23:17:58.406] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:38:0)
[23:17:58.406] Timer tick, asking Buildbucket for the build status
[23:17:59.275] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:59.275] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:39:0) after 1m0s
[23:18:59.333] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:39:0)
[23:18:59.333] Timer tick, asking Buildbucket for the build status
[23:18:59.451] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:59.451] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:40:0) after 1m0s
[23:19:59.500] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:40:0)
[23:19:59.500] Timer tick, asking Buildbucket for the build status
[23:19:59.603] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:59.603] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:41:0) after 1m0s
[23:20:59.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:41:0)
[23:20:59.702] Timer tick, asking Buildbucket for the build status
[23:21:01.373] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:01.374] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:42:0) after 1m0s
[23:22:01.389] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:42:0)
[23:22:01.389] Timer tick, asking Buildbucket for the build status
[23:22:01.539] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:01.539] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:43:0) after 1m0s
[23:23:01.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:43:0)
[23:23:01.569] Timer tick, asking Buildbucket for the build status
[23:23:01.922] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:01.922] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:44:0) after 1m0s
[23:24:02.189] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:44:0)
[23:24:02.189] Timer tick, asking Buildbucket for the build status
[23:24:02.293] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:02.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:45:0) after 1m0s
[23:25:02.423] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:45:0)
[23:25:02.423] Timer tick, asking Buildbucket for the build status
[23:25:02.840] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:02.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:46:0) after 1m0s
[23:26:02.866] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:46:0)
[23:26:02.866] Timer tick, asking Buildbucket for the build status
[23:26:03.307] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:03.307] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:47:0) after 1m0s
[23:27:03.337] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:47:0)
[23:27:03.337] Timer tick, asking Buildbucket for the build status
[23:27:03.461] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:03.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:48:0) after 1m0s
[23:28:03.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:48:0)
[23:28:03.590] Timer tick, asking Buildbucket for the build status
[23:28:03.891] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:03.891] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:49:0) after 1m0s
[23:29:03.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:49:0)
[23:29:03.890] Timer tick, asking Buildbucket for the build status
[23:29:04.438] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:04.438] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:50:0) after 1m0s
[23:30:04.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:50:0)
[23:30:04.533] Timer tick, asking Buildbucket for the build status
[23:30:04.632] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:04.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:51:0) after 1m0s
[23:31:04.749] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:51:0)
[23:31:04.749] Timer tick, asking Buildbucket for the build status
[23:31:04.997] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:04.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:52:0) after 1m0s
[23:32:05.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:52:0)
[23:32:05.036] Timer tick, asking Buildbucket for the build status
[23:32:05.160] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:05.160] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:53:0) after 1m0s
[23:33:05.223] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:53:0)
[23:33:05.223] Timer tick, asking Buildbucket for the build status
[23:33:05.414] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:05.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:54:0) after 1m0s
[23:34:05.396] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:54:0)
[23:34:05.396] Timer tick, asking Buildbucket for the build status
[23:34:05.587] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:05.587] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:55:0) after 1m0s
[23:35:05.617] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:55:0)
[23:35:05.617] Timer tick, asking Buildbucket for the build status
[23:35:05.727] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:05.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:56:0) after 1m0s
[23:36:05.975] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:56:0)
[23:36:05.975] Timer tick, asking Buildbucket for the build status
[23:36:06.105] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:06.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:57:0) after 1m0s
[23:37:06.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:57:0)
[23:37:06.398] Timer tick, asking Buildbucket for the build status
[23:37:06.793] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:06.793] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:58:0) after 1m0s
[23:38:06.959] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:58:0)
[23:38:06.959] Timer tick, asking Buildbucket for the build status
[23:38:07.278] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:07.278] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:59:0) after 1m0s
[23:39:07.368] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:59:0)
[23:39:07.368] Timer tick, asking Buildbucket for the build status
[23:39:07.754] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:39:07.754] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:60:0) after 1m0s
[23:40:07.800] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:60:0)
[23:40:07.800] Timer tick, asking Buildbucket for the build status
[23:40:07.950] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:07.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:61:0) after 1m0s
[23:41:08.168] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:61:0)
[23:41:08.168] Timer tick, asking Buildbucket for the build status
[23:41:08.282] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:08.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:62:0) after 1m0s
[23:42:08.348] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:62:0)
[23:42:08.348] Timer tick, asking Buildbucket for the build status
[23:42:08.459] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:08.459] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:63:0) after 1m0s
[23:43:08.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:63:0)
[23:43:08.519] Timer tick, asking Buildbucket for the build status
[23:43:08.679] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:08.679] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:64:0) after 1m0s
[23:44:08.732] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:64:0)
[23:44:08.732] Timer tick, asking Buildbucket for the build status
[23:44:08.910] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:08.910] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:65:0) after 1m0s
[23:45:08.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:65:0)
[23:45:08.965] Timer tick, asking Buildbucket for the build status
[23:45:09.345] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:09.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:66:0) after 1m0s
[23:46:09.442] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:66:0)
[23:46:09.442] Timer tick, asking Buildbucket for the build status
[23:46:09.588] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:09.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:67:0) after 1m0s
[23:47:09.644] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:67:0)
[23:47:09.644] Timer tick, asking Buildbucket for the build status
[23:47:09.851] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:09.851] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:68:0) after 1m0s
[23:48:09.856] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:68:0)
[23:48:09.856] Timer tick, asking Buildbucket for the build status
[23:48:10.042] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:10.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:69:0) after 1m0s
[23:49:10.102] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:69:0)
[23:49:10.102] Timer tick, asking Buildbucket for the build status
[23:49:10.341] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:10.341] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:70:0) after 1m0s
[23:50:10.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:70:0)
[23:50:10.382] Timer tick, asking Buildbucket for the build status
[23:50:10.585] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:10.585] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:71:0) after 1m0s
[23:51:10.635] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:71:0)
[23:51:10.635] Timer tick, asking Buildbucket for the build status
[23:51:10.975] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:10.975] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:72:0) after 1m0s
[23:52:11.184] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:72:0)
[23:52:11.184] Timer tick, asking Buildbucket for the build status
[23:52:11.333] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:11.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:73:0) after 1m0s
[23:53:11.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:73:0)
[23:53:11.403] Timer tick, asking Buildbucket for the build status
[23:53:11.557] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:11.557] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:74:0) after 1m0s
[23:54:11.584] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:74:0)
[23:54:11.584] Timer tick, asking Buildbucket for the build status
[23:54:11.860] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:11.860] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:75:0) after 1m0s
[23:55:11.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:75:0)
[23:55:11.868] Timer tick, asking Buildbucket for the build status
[23:55:11.984] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:11.984] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:76:0) after 1m0s
[23:56:12.104] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:76:0)
[23:56:12.104] Timer tick, asking Buildbucket for the build status
[23:56:12.267] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:12.267] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:77:0) after 1m0s
[23:57:12.341] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:77:0)
[23:57:12.341] Timer tick, asking Buildbucket for the build status
[23:57:12.607] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:12.607] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:78:0) after 1m0s
[23:58:12.684] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:78:0)
[23:58:12.684] Timer tick, asking Buildbucket for the build status
[23:58:12.844] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:12.844] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:79:0) after 1m0s
[23:59:12.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:79:0)
[23:59:12.948] Timer tick, asking Buildbucket for the build status
[23:59:13.090] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:13.090] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:80:0) after 1m0s
[00:00:13.197] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:80:0)
[00:00:13.197] Timer tick, asking Buildbucket for the build status
[00:00:13.504] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:13.504] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:81:0) after 1m0s
[00:01:13.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:81:0)
[00:01:13.572] Timer tick, asking Buildbucket for the build status
[00:01:13.970] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:13.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:82:0) after 1m0s
[00:02:13.958] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:82:0)
[00:02:13.958] Timer tick, asking Buildbucket for the build status
[00:02:14.171] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:14.171] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:83:0) after 1m0s
[00:03:14.283] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:83:0)
[00:03:14.283] Timer tick, asking Buildbucket for the build status
[00:03:14.406] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:14.406] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:84:0) after 1m0s
[00:04:14.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:84:0)
[00:04:14.602] Timer tick, asking Buildbucket for the build status
[00:04:14.731] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:14.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:85:0) after 1m0s
[00:05:15.065] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:85:0)
[00:05:15.065] Timer tick, asking Buildbucket for the build status
[00:05:15.208] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:15.208] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:86:0) after 1m0s
[00:06:15.277] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:86:0)
[00:06:15.277] Timer tick, asking Buildbucket for the build status
[00:06:15.727] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:15.727] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:87:0) after 1m0s
[00:07:15.960] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:87:0)
[00:07:15.960] Timer tick, asking Buildbucket for the build status
[00:07:16.470] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:16.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:88:0) after 1m0s
[00:08:16.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:88:0)
[00:08:16.682] Timer tick, asking Buildbucket for the build status
[00:08:17.008] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:17.008] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:89:0) after 1m0s
[00:09:17.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:89:0)
[00:09:17.011] Timer tick, asking Buildbucket for the build status
[00:09:17.212] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:17.212] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:90:0) after 1m0s
[00:10:17.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:90:0)
[00:10:17.292] Timer tick, asking Buildbucket for the build status
[00:10:17.414] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:17.414] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:91:0) after 1m0s
[00:11:17.451] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:91:0)
[00:11:17.451] Timer tick, asking Buildbucket for the build status
[00:11:17.877] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:17.877] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:92:0) after 1m0s
[00:12:17.934] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:92:0)
[00:12:17.934] Timer tick, asking Buildbucket for the build status
[00:12:18.182] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:18.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:93:0) after 1m0s
[00:13:18.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:93:0)
[00:13:18.236] Timer tick, asking Buildbucket for the build status
[00:13:19.949] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:19.949] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:94:0) after 1m0s
[00:14:19.995] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:94:0)
[00:14:19.995] Timer tick, asking Buildbucket for the build status
[00:14:20.313] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:20.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:95:0) after 1m0s
[00:15:20.382] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:95:0)
[00:15:20.382] Timer tick, asking Buildbucket for the build status
[00:15:21.195] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:21.195] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:96:0) after 1m0s
[00:16:21.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:96:0)
[00:16:21.249] Timer tick, asking Buildbucket for the build status
[00:16:21.422] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:21.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:97:0) after 1m0s
[00:17:21.538] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:97:0)
[00:17:21.538] Timer tick, asking Buildbucket for the build status
[00:17:21.648] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:21.648] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:98:0) after 1m0s
[00:18:21.810] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:98:0)
[00:18:21.810] Timer tick, asking Buildbucket for the build status
[00:18:21.983] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:21.983] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:99:0) after 1m0s
[00:19:22.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:99:0)
[00:19:22.079] Timer tick, asking Buildbucket for the build status
[00:19:22.568] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:22.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:100:0) after 1m0s
[00:20:22.664] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:100:0)
[00:20:22.664] Timer tick, asking Buildbucket for the build status
[00:20:22.854] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:22.854] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:101:0) after 1m0s
[00:21:22.881] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:101:0)
[00:21:22.881] Timer tick, asking Buildbucket for the build status
[00:21:23.448] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:23.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:102:0) after 1m0s
[00:22:23.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:102:0)
[00:22:23.491] Timer tick, asking Buildbucket for the build status
[00:22:23.708] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:23.708] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:103:0) after 1m0s
[00:23:23.808] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:103:0)
[00:23:23.808] Timer tick, asking Buildbucket for the build status
[00:23:24.123] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:24.123] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:104:0) after 1m0s
[00:24:24.274] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:104:0)
[00:24:24.274] Timer tick, asking Buildbucket for the build status
[00:24:24.686] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:24.686] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:105:0) after 1m0s
[00:25:24.880] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:105:0)
[00:25:24.880] Timer tick, asking Buildbucket for the build status
[00:25:25.107] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:25.107] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:106:0) after 1m0s
[00:26:25.149] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:106:0)
[00:26:25.149] Timer tick, asking Buildbucket for the build status
[00:26:25.615] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:25.615] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:107:0) after 1m0s
[00:27:25.683] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:107:0)
[00:27:25.683] Timer tick, asking Buildbucket for the build status
[00:27:25.896] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:25.896] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:108:0) after 1m0s
[00:28:26.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:108:0)
[00:28:26.048] Timer tick, asking Buildbucket for the build status
[00:28:26.172] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:26.172] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:109:0) after 1m0s
[00:29:26.207] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:109:0)
[00:29:26.207] Timer tick, asking Buildbucket for the build status
[00:29:26.750] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:26.750] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:110:0) after 1m0s
[00:30:26.869] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:110:0)
[00:30:26.869] Timer tick, asking Buildbucket for the build status
[00:30:27.513] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:27.513] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:111:0) after 1m0s
[00:31:27.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:111:0)
[00:31:27.659] Timer tick, asking Buildbucket for the build status
[00:31:27.916] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:27.916] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:112:0) after 1m0s
[00:32:27.971] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:112:0)
[00:32:27.971] Timer tick, asking Buildbucket for the build status
[00:32:28.311] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:32:28.311] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:113:0) after 1m0s
[00:33:28.415] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:113:0)
[00:33:28.415] Timer tick, asking Buildbucket for the build status
[00:33:28.554] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:33:28.554] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:114:0) after 1m0s
[00:34:28.581] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:114:0)
[00:34:28.581] Timer tick, asking Buildbucket for the build status
[00:34:29.062] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:34:29.062] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:115:0) after 1m0s
[00:35:29.229] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:115:0)
[00:35:29.229] Timer tick, asking Buildbucket for the build status
[00:35:29.407] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:35:29.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:116:0) after 1m0s
[00:36:29.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:116:0)
[00:36:29.446] Timer tick, asking Buildbucket for the build status
[00:36:29.885] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:36:29.885] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:117:0) after 1m0s
[00:37:30.070] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:117:0)
[00:37:30.070] Timer tick, asking Buildbucket for the build status
[00:37:31.333] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:37:31.333] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:118:0) after 1m0s
[00:38:31.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:118:0)
[00:38:31.475] Timer tick, asking Buildbucket for the build status
[00:38:31.838] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:38:31.838] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:119:0) after 1m0s
[00:39:31.886] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:119:0)
[00:39:31.886] Timer tick, asking Buildbucket for the build status
[00:39:32.146] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:39:32.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:120:0) after 1m0s
[00:40:32.232] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:120:0)
[00:40:32.232] Timer tick, asking Buildbucket for the build status
[00:40:32.482] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:40:32.482] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:121:0) after 1m0s
[00:41:32.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:121:0)
[00:41:32.494] Timer tick, asking Buildbucket for the build status
[00:41:32.642] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:41:32.642] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:122:0) after 1m0s
[00:42:32.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:122:0)
[00:42:32.642] Timer tick, asking Buildbucket for the build status
[00:42:32.771] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:42:32.771] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:123:0) after 1m0s
[00:43:32.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:123:0)
[00:43:32.830] Timer tick, asking Buildbucket for the build status
[00:43:32.950] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:43:32.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:124:0) after 1m0s
[00:44:32.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:124:0)
[00:44:32.996] Timer tick, asking Buildbucket for the build status
[00:44:33.147] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:44:33.147] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:125:0) after 1m0s
[00:45:33.247] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:125:0)
[00:45:33.247] Timer tick, asking Buildbucket for the build status
[00:45:33.523] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:45:33.523] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:126:0) after 1m0s
[00:46:33.793] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:126:0)
[00:46:33.793] Timer tick, asking Buildbucket for the build status
[00:46:34.153] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:46:34.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:127:0) after 1m0s
[00:47:34.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:127:0)
[00:47:34.299] Timer tick, asking Buildbucket for the build status
[00:47:34.447] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:47:34.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:128:0) after 1m0s
[00:48:34.709] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:128:0)
[00:48:34.709] Timer tick, asking Buildbucket for the build status
[00:48:35.146] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:48:35.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:129:0) after 1m0s
[00:49:35.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:129:0)
[00:49:35.339] Timer tick, asking Buildbucket for the build status
[00:49:35.544] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:49:35.544] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:130:0) after 1m0s
[00:50:35.590] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:130:0)
[00:50:35.590] Timer tick, asking Buildbucket for the build status
[00:50:35.741] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:50:35.741] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:131:0) after 1m0s
[00:51:35.748] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:131:0)
[00:51:35.748] Timer tick, asking Buildbucket for the build status
[00:51:36.142] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:51:36.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:132:0) after 1m0s
[00:52:36.256] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:132:0)
[00:52:36.256] Timer tick, asking Buildbucket for the build status
[00:52:36.807] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:52:36.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:133:0) after 1m0s
[00:53:36.901] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:133:0)
[00:53:36.901] Timer tick, asking Buildbucket for the build status
[00:53:37.039] Build 8891067353595882256: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:53:37.039] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:134:0) after 1m0s
[00:54:25.363] Received PubSub notification, asking Buildbucket for the build status
[00:54:25.467] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:37.155] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:134:0)
[00:54:37.155] Timer tick, asking Buildbucket for the build status
[00:54:37.233] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:37.233] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:136:0) after 1m0s
[00:55:37.293] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:136:0)
[00:55:37.293] Timer tick, asking Buildbucket for the build status
[00:55:37.563] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:37.563] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:137:0) after 1m0s
[00:56:37.596] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:137:0)
[00:56:37.596] Timer tick, asking Buildbucket for the build status
[00:56:38.043] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:38.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:138:0) after 1m0s
[00:57:38.200] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:138:0)
[00:57:38.200] Timer tick, asking Buildbucket for the build status
[00:57:38.334] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:38.334] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:139:0) after 1m0s
[00:58:38.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:139:0)
[00:58:38.352] Timer tick, asking Buildbucket for the build status
[00:58:38.616] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:38.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:140:0) after 1m0s
[00:59:38.698] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:140:0)
[00:59:38.698] Timer tick, asking Buildbucket for the build status
[00:59:39.095] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:39.095] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:141:0) after 1m0s
[01:00:39.300] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:141:0)
[01:00:39.300] Timer tick, asking Buildbucket for the build status
[01:00:39.448] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:39.448] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:142:0) after 1m0s
[01:01:39.493] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:142:0)
[01:01:39.493] Timer tick, asking Buildbucket for the build status
[01:01:39.928] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:39.928] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:143:0) after 1m0s
[01:02:39.936] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:143:0)
[01:02:39.936] Timer tick, asking Buildbucket for the build status
[01:02:40.142] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:40.142] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:144:0) after 1m0s
[01:03:40.176] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:144:0)
[01:03:40.176] Timer tick, asking Buildbucket for the build status
[01:03:40.318] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:40.318] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:145:0) after 1m0s
[01:04:40.311] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:145:0)
[01:04:40.311] Timer tick, asking Buildbucket for the build status
[01:04:40.457] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:40.457] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:146:0) after 1m0s
[01:05:40.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:146:0)
[01:05:40.478] Timer tick, asking Buildbucket for the build status
[01:05:40.786] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:40.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:147:0) after 1m0s
[01:06:40.968] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:147:0)
[01:06:40.968] Timer tick, asking Buildbucket for the build status
[01:06:41.247] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:41.247] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:148:0) after 1m0s
[01:07:41.246] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:148:0)
[01:07:41.246] Timer tick, asking Buildbucket for the build status
[01:07:41.443] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:41.443] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:149:0) after 1m0s
[01:08:41.608] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:149:0)
[01:08:41.608] Timer tick, asking Buildbucket for the build status
[01:08:42.694] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:42.694] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:150:0) after 1m0s
[01:09:42.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:150:0)
[01:09:42.719] Timer tick, asking Buildbucket for the build status
[01:09:42.850] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:42.850] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:151:0) after 1m0s
[01:10:42.921] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:151:0)
[01:10:42.921] Timer tick, asking Buildbucket for the build status
[01:10:43.209] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:43.209] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:152:0) after 1m0s
[01:11:43.344] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:152:0)
[01:11:43.344] Timer tick, asking Buildbucket for the build status
[01:11:43.709] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:43.709] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:153:0) after 1m0s
[01:12:43.763] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:153:0)
[01:12:43.763] Timer tick, asking Buildbucket for the build status
[01:12:43.944] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:43.944] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:154:0) after 1m0s
[01:13:43.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:154:0)
[01:13:43.977] Timer tick, asking Buildbucket for the build status
[01:13:44.302] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:44.302] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:155:0) after 1m0s
[01:14:44.360] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:155:0)
[01:14:44.360] Timer tick, asking Buildbucket for the build status
[01:14:44.564] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:44.564] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:156:0) after 1m0s
[01:15:44.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:156:0)
[01:15:44.593] Timer tick, asking Buildbucket for the build status
[01:15:44.810] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:44.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:157:0) after 1m0s
[01:16:45.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:157:0)
[01:16:45.147] Timer tick, asking Buildbucket for the build status
[01:16:45.469] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:45.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:158:0) after 1m0s
[01:17:45.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:158:0)
[01:17:45.496] Timer tick, asking Buildbucket for the build status
[01:17:45.612] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:45.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:159:0) after 1m0s
[01:18:45.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:159:0)
[01:18:45.723] Timer tick, asking Buildbucket for the build status
[01:18:46.182] Build 8891067353595882256: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:46.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9056497415862793600:160:0) after 1m0s
[01:19:21.720] Received PubSub notification, asking Buildbucket for the build status
[01:19:21.833] Build 8891067353595882256: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:19:21.833] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"completed_ts": "1579223961178127",
"created_by": "project:infra",
"created_ts": "1579214441645098",
"failure_reason": "BUILD_FAILURE",
"id": "8891067353595882256",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"79.0.3945.130\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5a55f8f3-4a3b-41ad-ab64-d071f1cff7ed\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"79.0.3945.130\"}}",
"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.3945.130\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"5a55f8f3-4a3b-41ad-ab64-d071f1cff7ed\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-ku4i\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"79.0.3945.130\"}, \"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-ku4i\"], \"image\": [\"chrome-xenial-19121500-b03549ec744\"], \"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\": [\"4760-d9a0e6d\"], \"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": "1579222464039707",
"status": "COMPLETED",
"status_changed_ts": "1579222464513706",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9056497415862793600",
"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/8891067353595882256/+/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:49c95597f5fe9010",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1579223961243236",
"url": "https://ci.chromium.org/b/8891067353595882256",
"utcnow_ts": "1579223961801305"
}
}
[01:19:21.833] Invocation finished in 2h38m41.733717761s with status FAILED