[23:15:50.164] New invocation is queued and will start shortly
[23:15:50.164] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[23:15:51.341] Starting the invocation (attempt 1)
[23:15:51.382] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:15:51.382] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:15:51.382] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9051693564096997088",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"80.0.3987.137\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"113333b8-2ee7-4a2e-8aad-dd549b19396e\",\"title\":\"publish_tarball/0\"}]},\"version\":\"80.0.3987.137\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9051693564096997088",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[23:15:52.387] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:infra",
"created_ts": "1583795751522133",
"id": "8886263502010514000",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.137\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"113333b8-2ee7-4a2e-8aad-dd549b19396e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"80.0.3987.137\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1583795752021955",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9051693564096997088",
"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/8886263502010514000/+/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": "1583795752022110",
"url": "https://ci.chromium.org/b/8886263502010514000",
"utcnow_ts": "1583795752357676"
}
}
[23:15:52.387] Task URL: https://ci.chromium.org/b/8886263502010514000
[23:15:52.387] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:2:0) after 1m0s
[23:16:52.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:2:0)
[23:16:52.536] Timer tick, asking Buildbucket for the build status
[23:16:52.953] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:16:52.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:3:0) after 1m0s
[23:17:53.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:3:0)
[23:17:53.021] Timer tick, asking Buildbucket for the build status
[23:17:53.277] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:17:53.277] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:4:0) after 1m0s
[23:18:53.331] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:4:0)
[23:18:53.331] Timer tick, asking Buildbucket for the build status
[23:18:53.855] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:18:53.855] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:5:0) after 1m0s
[23:19:53.872] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:5:0)
[23:19:53.872] Timer tick, asking Buildbucket for the build status
[23:19:54.304] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:19:54.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:6:0) after 1m0s
[23:20:54.319] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:6:0)
[23:20:54.319] Timer tick, asking Buildbucket for the build status
[23:20:54.501] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:20:54.501] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:7:0) after 1m0s
[23:21:54.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:7:0)
[23:21:54.592] Timer tick, asking Buildbucket for the build status
[23:21:54.743] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:21:54.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:8:0) after 1m0s
[23:22:54.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:8:0)
[23:22:54.722] Timer tick, asking Buildbucket for the build status
[23:22:54.816] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:22:54.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:9:0) after 1m0s
[23:23:54.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:9:0)
[23:23:54.950] Timer tick, asking Buildbucket for the build status
[23:23:55.153] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:23:55.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:10:0) after 1m0s
[23:24:55.569] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:10:0)
[23:24:55.569] Timer tick, asking Buildbucket for the build status
[23:24:55.684] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:24:55.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:11:0) after 1m0s
[23:25:55.973] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:11:0)
[23:25:55.973] Timer tick, asking Buildbucket for the build status
[23:25:56.078] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:25:56.078] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:12:0) after 1m0s
[23:26:56.142] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:12:0)
[23:26:56.142] Timer tick, asking Buildbucket for the build status
[23:26:56.269] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:26:56.269] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:13:0) after 1m0s
[23:27:56.327] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:13:0)
[23:27:56.327] Timer tick, asking Buildbucket for the build status
[23:27:56.596] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:27:56.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:14:0) after 1m0s
[23:28:56.650] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:14:0)
[23:28:56.650] Timer tick, asking Buildbucket for the build status
[23:28:56.789] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:28:56.789] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:15:0) after 1m0s
[23:29:56.850] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:15:0)
[23:29:56.850] Timer tick, asking Buildbucket for the build status
[23:29:56.926] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:29:56.926] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:16:0) after 1m0s
[23:30:57.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:16:0)
[23:30:57.126] Timer tick, asking Buildbucket for the build status
[23:30:57.375] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:30:57.375] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:17:0) after 1m0s
[23:31:57.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:17:0)
[23:31:57.522] Timer tick, asking Buildbucket for the build status
[23:31:57.630] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:31:57.630] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:18:0) after 1m0s
[23:32:57.859] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:18:0)
[23:32:57.859] Timer tick, asking Buildbucket for the build status
[23:32:57.921] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:32:57.921] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:19:0) after 1m0s
[23:33:58.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:19:0)
[23:33:58.069] Timer tick, asking Buildbucket for the build status
[23:33:58.159] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:33:58.159] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:20:0) after 1m0s
[23:34:58.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:20:0)
[23:34:58.519] Timer tick, asking Buildbucket for the build status
[23:34:58.661] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:34:58.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:21:0) after 1m0s
[23:35:58.890] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:21:0)
[23:35:58.890] Timer tick, asking Buildbucket for the build status
[23:35:59.023] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:35:59.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:22:0) after 1m0s
[23:36:59.236] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:22:0)
[23:36:59.236] Timer tick, asking Buildbucket for the build status
[23:36:59.437] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:36:59.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:23:0) after 1m0s
[23:37:59.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:23:0)
[23:37:59.552] Timer tick, asking Buildbucket for the build status
[23:37:59.658] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:37:59.658] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:24:0) after 1m0s
[23:38:59.731] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:24:0)
[23:38:59.731] Timer tick, asking Buildbucket for the build status
[23:38:59.858] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:38:59.858] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:25:0) after 1m0s
[23:39:59.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:25:0)
[23:39:59.904] Timer tick, asking Buildbucket for the build status
[23:40:00.068] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:40:00.068] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:26:0) after 1m0s
[23:41:00.095] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:26:0)
[23:41:00.095] Timer tick, asking Buildbucket for the build status
[23:41:00.215] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:41:00.215] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:27:0) after 1m0s
[23:42:00.322] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:27:0)
[23:42:00.322] Timer tick, asking Buildbucket for the build status
[23:42:00.461] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:42:00.461] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:28:0) after 1m0s
[23:43:00.513] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:28:0)
[23:43:00.513] Timer tick, asking Buildbucket for the build status
[23:43:00.636] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:43:00.636] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:29:0) after 1m0s
[23:44:00.693] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:29:0)
[23:44:00.693] Timer tick, asking Buildbucket for the build status
[23:44:01.018] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:44:01.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:30:0) after 1m0s
[23:45:01.164] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:30:0)
[23:45:01.164] Timer tick, asking Buildbucket for the build status
[23:45:01.536] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:45:01.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:31:0) after 1m0s
[23:46:01.563] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:31:0)
[23:46:01.563] Timer tick, asking Buildbucket for the build status
[23:46:01.747] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:46:01.747] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:32:0) after 1m0s
[23:47:01.758] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:32:0)
[23:47:01.758] Timer tick, asking Buildbucket for the build status
[23:47:01.980] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:47:01.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:33:0) after 1m0s
[23:48:02.116] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:33:0)
[23:48:02.116] Timer tick, asking Buildbucket for the build status
[23:48:02.520] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:48:02.520] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:34:0) after 1m0s
[23:49:02.560] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:34:0)
[23:49:02.560] Timer tick, asking Buildbucket for the build status
[23:49:02.652] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:49:02.652] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:35:0) after 1m0s
[23:50:02.715] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:35:0)
[23:50:02.715] Timer tick, asking Buildbucket for the build status
[23:50:02.906] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:50:02.906] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:36:0) after 1m0s
[23:51:03.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:36:0)
[23:51:03.011] Timer tick, asking Buildbucket for the build status
[23:51:03.408] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:51:03.408] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:37:0) after 1m0s
[23:52:03.446] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:37:0)
[23:52:03.446] Timer tick, asking Buildbucket for the build status
[23:52:03.711] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:52:03.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:38:0) after 1m0s
[23:53:03.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:38:0)
[23:53:03.789] Timer tick, asking Buildbucket for the build status
[23:53:03.994] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:53:03.994] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:39:0) after 1m0s
[23:54:04.122] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:39:0)
[23:54:04.122] Timer tick, asking Buildbucket for the build status
[23:54:04.229] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:54:04.229] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:40:0) after 1m0s
[23:55:04.323] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:40:0)
[23:55:04.323] Timer tick, asking Buildbucket for the build status
[23:55:04.440] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:55:04.440] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:41:0) after 1m0s
[23:56:04.495] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:41:0)
[23:56:04.495] Timer tick, asking Buildbucket for the build status
[23:56:04.596] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:56:04.596] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:42:0) after 1m0s
[23:57:04.836] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:42:0)
[23:57:04.836] Timer tick, asking Buildbucket for the build status
[23:57:05.153] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:57:05.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:43:0) after 1m0s
[23:58:05.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:43:0)
[23:58:05.354] Timer tick, asking Buildbucket for the build status
[23:58:05.516] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:58:05.516] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:44:0) after 1m0s
[23:59:05.613] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:44:0)
[23:59:05.613] Timer tick, asking Buildbucket for the build status
[23:59:05.731] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[23:59:05.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:45:0) after 1m0s
[00:00:05.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:45:0)
[00:00:05.774] Timer tick, asking Buildbucket for the build status
[00:00:05.954] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:00:05.954] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:46:0) after 1m0s
[00:01:05.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:46:0)
[00:01:05.972] Timer tick, asking Buildbucket for the build status
[00:01:06.106] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:01:06.106] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:47:0) after 1m0s
[00:02:06.158] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:47:0)
[00:02:06.158] Timer tick, asking Buildbucket for the build status
[00:02:06.490] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:02:06.490] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:48:0) after 1m0s
[00:03:06.521] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:48:0)
[00:03:06.521] Timer tick, asking Buildbucket for the build status
[00:03:06.625] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:03:06.625] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:49:0) after 1m0s
[00:04:06.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:49:0)
[00:04:06.657] Timer tick, asking Buildbucket for the build status
[00:04:06.757] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:04:06.757] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:50:0) after 1m0s
[00:05:06.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:50:0)
[00:05:06.805] Timer tick, asking Buildbucket for the build status
[00:05:07.042] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:05:07.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:51:0) after 1m0s
[00:06:07.183] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:51:0)
[00:06:07.183] Timer tick, asking Buildbucket for the build status
[00:06:07.450] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:06:07.450] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:52:0) after 1m0s
[00:07:07.506] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:52:0)
[00:07:07.506] Timer tick, asking Buildbucket for the build status
[00:07:07.623] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:07:07.623] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:53:0) after 1m0s
[00:08:07.658] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:53:0)
[00:08:07.658] Timer tick, asking Buildbucket for the build status
[00:08:07.841] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:08:07.841] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:54:0) after 1m0s
[00:09:07.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:54:0)
[00:09:07.986] Timer tick, asking Buildbucket for the build status
[00:09:08.100] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:09:08.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:55:0) after 1m0s
[00:10:08.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:55:0)
[00:10:08.098] Timer tick, asking Buildbucket for the build status
[00:10:08.210] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:10:08.210] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:56:0) after 1m0s
[00:11:08.264] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:56:0)
[00:11:08.264] Timer tick, asking Buildbucket for the build status
[00:11:08.781] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:11:08.781] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:57:0) after 1m0s
[00:12:08.804] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:57:0)
[00:12:08.804] Timer tick, asking Buildbucket for the build status
[00:12:08.931] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:12:08.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:58:0) after 1m0s
[00:13:09.210] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:58:0)
[00:13:09.210] Timer tick, asking Buildbucket for the build status
[00:13:09.342] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:13:09.342] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:59:0) after 1m0s
[00:14:09.366] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:59:0)
[00:14:09.366] Timer tick, asking Buildbucket for the build status
[00:14:09.442] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:14:09.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:60:0) after 1m0s
[00:15:09.579] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:60:0)
[00:15:09.579] Timer tick, asking Buildbucket for the build status
[00:15:09.688] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:15:09.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:61:0) after 1m0s
[00:16:09.867] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:61:0)
[00:16:09.867] Timer tick, asking Buildbucket for the build status
[00:16:10.166] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:16:10.166] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:62:0) after 1m0s
[00:17:10.188] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:62:0)
[00:17:10.188] Timer tick, asking Buildbucket for the build status
[00:17:10.473] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:17:10.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:63:0) after 1m0s
[00:18:10.522] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:63:0)
[00:18:10.522] Timer tick, asking Buildbucket for the build status
[00:18:10.783] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:18:10.783] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:64:0) after 1m0s
[00:19:10.830] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:64:0)
[00:19:10.830] Timer tick, asking Buildbucket for the build status
[00:19:11.143] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:19:11.143] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:65:0) after 1m0s
[00:20:11.198] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:65:0)
[00:20:11.198] Timer tick, asking Buildbucket for the build status
[00:20:11.383] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:20:11.383] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:66:0) after 1m0s
[00:21:11.384] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:66:0)
[00:21:11.384] Timer tick, asking Buildbucket for the build status
[00:21:11.705] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:21:11.705] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:67:0) after 1m0s
[00:22:11.708] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:67:0)
[00:22:11.708] Timer tick, asking Buildbucket for the build status
[00:22:11.995] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:22:11.995] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:68:0) after 1m0s
[00:23:12.150] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:68:0)
[00:23:12.150] Timer tick, asking Buildbucket for the build status
[00:23:12.259] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:23:12.259] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:69:0) after 1m0s
[00:24:12.279] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:69:0)
[00:24:12.279] Timer tick, asking Buildbucket for the build status
[00:24:12.456] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:24:12.456] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:70:0) after 1m0s
[00:25:12.477] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:70:0)
[00:25:12.477] Timer tick, asking Buildbucket for the build status
[00:25:12.749] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:25:12.749] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:71:0) after 1m0s
[00:26:12.774] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:71:0)
[00:26:12.774] Timer tick, asking Buildbucket for the build status
[00:26:12.923] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:26:12.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:72:0) after 1m0s
[00:27:13.139] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:72:0)
[00:27:13.139] Timer tick, asking Buildbucket for the build status
[00:27:13.276] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:27:13.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:73:0) after 1m0s
[00:28:13.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:73:0)
[00:28:13.258] Timer tick, asking Buildbucket for the build status
[00:28:13.470] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:28:13.470] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:74:0) after 1m0s
[00:29:13.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:74:0)
[00:29:13.562] Timer tick, asking Buildbucket for the build status
[00:29:13.661] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:29:13.661] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:75:0) after 1m0s
[00:30:13.701] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:75:0)
[00:30:13.701] Timer tick, asking Buildbucket for the build status
[00:30:13.794] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:30:13.794] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:76:0) after 1m0s
[00:31:13.989] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:76:0)
[00:31:13.989] Timer tick, asking Buildbucket for the build status
[00:31:14.201] Build 8886263502010514000: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[00:31:14.201] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:77:0) after 1m0s
[00:31:58.320] Received PubSub notification, asking Buildbucket for the build status
[00:31:58.403] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:14.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:77:0)
[00:32:14.301] Timer tick, asking Buildbucket for the build status
[00:32:14.420] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:14.420] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:79:0) after 1m0s
[00:33:14.441] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:79:0)
[00:33:14.441] Timer tick, asking Buildbucket for the build status
[00:33:14.601] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:14.601] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:80:0) after 1m0s
[00:34:14.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:80:0)
[00:34:14.673] Timer tick, asking Buildbucket for the build status
[00:34:15.146] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:15.146] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:81:0) after 1m0s
[00:35:15.205] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:81:0)
[00:35:15.205] Timer tick, asking Buildbucket for the build status
[00:35:15.437] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:15.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:82:0) after 1m0s
[00:36:15.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:82:0)
[00:36:15.526] Timer tick, asking Buildbucket for the build status
[00:36:15.778] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:15.778] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:83:0) after 1m0s
[00:37:15.789] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:83:0)
[00:37:15.789] Timer tick, asking Buildbucket for the build status
[00:37:15.998] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:15.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:84:0) after 1m0s
[00:38:16.035] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:84:0)
[00:38:16.035] Timer tick, asking Buildbucket for the build status
[00:38:16.235] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:16.235] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:85:0) after 1m0s
[00:39:16.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:85:0)
[00:39:16.287] Timer tick, asking Buildbucket for the build status
[00:39:16.480] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:16.480] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:86:0) after 1m0s
[00:40:16.550] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:86:0)
[00:40:16.550] Timer tick, asking Buildbucket for the build status
[00:40:16.677] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:16.677] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:87:0) after 1m0s
[00:41:16.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:87:0)
[00:41:16.706] Timer tick, asking Buildbucket for the build status
[00:41:16.864] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:16.864] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:88:0) after 1m0s
[00:42:16.899] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:88:0)
[00:42:16.899] Timer tick, asking Buildbucket for the build status
[00:42:17.019] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:17.019] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:89:0) after 1m0s
[00:43:17.120] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:89:0)
[00:43:17.120] Timer tick, asking Buildbucket for the build status
[00:43:17.294] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:17.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:90:0) after 1m0s
[00:44:17.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:90:0)
[00:44:17.431] Timer tick, asking Buildbucket for the build status
[00:44:17.532] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:17.532] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:91:0) after 1m0s
[00:45:17.551] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:91:0)
[00:45:17.551] Timer tick, asking Buildbucket for the build status
[00:45:17.752] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:17.752] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:92:0) after 1m0s
[00:46:17.815] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:92:0)
[00:46:17.815] Timer tick, asking Buildbucket for the build status
[00:46:18.347] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:18.347] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:93:0) after 1m0s
[00:47:18.334] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:93:0)
[00:47:18.334] Timer tick, asking Buildbucket for the build status
[00:47:18.634] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:18.634] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:94:0) after 1m0s
[00:48:18.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:94:0)
[00:48:18.719] Timer tick, asking Buildbucket for the build status
[00:48:19.096] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:19.096] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:95:0) after 1m0s
[00:49:19.203] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:95:0)
[00:49:19.203] Timer tick, asking Buildbucket for the build status
[00:49:19.340] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:19.340] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:96:0) after 1m0s
[00:50:19.367] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:96:0)
[00:50:19.367] Timer tick, asking Buildbucket for the build status
[00:50:19.473] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:19.473] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:97:0) after 1m0s
[00:51:19.631] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:97:0)
[00:51:19.631] Timer tick, asking Buildbucket for the build status
[00:51:19.739] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:19.739] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:98:0) after 1m0s
[00:52:19.822] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:98:0)
[00:52:19.822] Timer tick, asking Buildbucket for the build status
[00:52:20.276] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:20.276] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:99:0) after 1m0s
[00:53:20.458] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:99:0)
[00:53:20.458] Timer tick, asking Buildbucket for the build status
[00:53:20.588] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:20.588] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:100:0) after 1m0s
[00:54:20.592] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:100:0)
[00:54:20.592] Timer tick, asking Buildbucket for the build status
[00:54:20.818] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:20.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:101:0) after 1m0s
[00:55:20.868] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:101:0)
[00:55:20.868] Timer tick, asking Buildbucket for the build status
[00:55:20.993] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:20.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:102:0) after 1m0s
[00:56:21.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:102:0)
[00:56:21.040] Timer tick, asking Buildbucket for the build status
[00:56:21.181] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:21.181] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:103:0) after 1m0s
[00:57:21.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:103:0)
[00:57:21.299] Timer tick, asking Buildbucket for the build status
[00:57:22.678] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:22.678] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:104:0) after 1m0s
[00:58:22.788] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:104:0)
[00:58:22.788] Timer tick, asking Buildbucket for the build status
[00:58:22.915] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:22.915] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:105:0) after 1m0s
[00:59:22.972] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:105:0)
[00:59:22.972] Timer tick, asking Buildbucket for the build status
[00:59:23.097] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:23.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:106:0) after 1m0s
[01:00:23.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:106:0)
[01:00:23.147] Timer tick, asking Buildbucket for the build status
[01:00:23.241] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:23.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:107:0) after 1m0s
[01:01:23.267] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:107:0)
[01:01:23.267] Timer tick, asking Buildbucket for the build status
[01:01:23.442] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:23.442] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:108:0) after 1m0s
[01:02:23.509] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:108:0)
[01:02:23.509] Timer tick, asking Buildbucket for the build status
[01:02:23.811] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:23.811] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:109:0) after 1m0s
[01:03:23.848] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:109:0)
[01:03:23.848] Timer tick, asking Buildbucket for the build status
[01:03:23.963] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:23.963] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:110:0) after 1m0s
[01:04:24.171] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:110:0)
[01:04:24.171] Timer tick, asking Buildbucket for the build status
[01:04:24.317] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:24.317] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:111:0) after 1m0s
[01:05:24.508] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:111:0)
[01:05:24.508] Timer tick, asking Buildbucket for the build status
[01:05:24.684] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:24.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:112:0) after 1m0s
[01:06:24.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:112:0)
[01:06:24.817] Timer tick, asking Buildbucket for the build status
[01:06:25.022] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:25.022] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:113:0) after 1m0s
[01:07:25.113] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:113:0)
[01:07:25.113] Timer tick, asking Buildbucket for the build status
[01:07:25.273] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:25.273] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:114:0) after 1m0s
[01:08:25.350] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:114:0)
[01:08:25.350] Timer tick, asking Buildbucket for the build status
[01:08:25.635] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:25.635] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:115:0) after 1m0s
[01:09:25.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:115:0)
[01:09:25.629] Timer tick, asking Buildbucket for the build status
[01:09:25.731] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:25.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:116:0) after 1m0s
[01:10:25.816] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:116:0)
[01:10:25.816] Timer tick, asking Buildbucket for the build status
[01:10:26.097] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:26.097] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:117:0) after 1m0s
[01:11:26.312] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:117:0)
[01:11:26.312] Timer tick, asking Buildbucket for the build status
[01:11:26.506] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:26.506] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:118:0) after 1m0s
[01:12:26.598] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:118:0)
[01:12:26.598] Timer tick, asking Buildbucket for the build status
[01:12:26.703] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:26.703] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:119:0) after 1m0s
[01:13:26.845] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:119:0)
[01:13:26.845] Timer tick, asking Buildbucket for the build status
[01:13:27.238] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:27.238] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:120:0) after 1m0s
[01:14:27.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:120:0)
[01:14:27.479] Timer tick, asking Buildbucket for the build status
[01:14:27.987] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:27.987] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:121:0) after 1m0s
[01:15:28.192] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:121:0)
[01:15:28.192] Timer tick, asking Buildbucket for the build status
[01:15:28.355] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:28.355] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:122:0) after 1m0s
[01:16:28.536] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:122:0)
[01:16:28.536] Timer tick, asking Buildbucket for the build status
[01:16:28.643] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:28.643] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:123:0) after 1m0s
[01:17:28.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:123:0)
[01:17:28.730] Timer tick, asking Buildbucket for the build status
[01:17:28.880] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:28.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:124:0) after 1m0s
[01:18:29.088] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:124:0)
[01:18:29.088] Timer tick, asking Buildbucket for the build status
[01:18:29.536] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:29.536] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:125:0) after 1m0s
[01:19:29.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:125:0)
[01:19:29.682] Timer tick, asking Buildbucket for the build status
[01:19:29.806] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:29.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:126:0) after 1m0s
[01:20:29.970] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:126:0)
[01:20:29.970] Timer tick, asking Buildbucket for the build status
[01:20:30.364] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:30.364] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:127:0) after 1m0s
[01:21:30.457] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:127:0)
[01:21:30.457] Timer tick, asking Buildbucket for the build status
[01:21:30.609] Build 8886263502010514000: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:30.609] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9051693564096997088:128:0) after 1m0s
[01:21:40.464] Received PubSub notification, asking Buildbucket for the build status
[01:21:40.536] Build 8886263502010514000: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[01:21:40.536] Buildbucket build:
{
"build": {
"bucket": "luci.infra.cron",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1583803300040012",
"created_by": "project:infra",
"created_ts": "1583795751522133",
"failure_reason": "BUILD_FAILURE",
"id": "8886263502010514000",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"80.0.3987.137\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"113333b8-2ee7-4a2e-8aad-dd549b19396e\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"80.0.3987.137\"}}",
"project": "infra",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"Step('Build chrome.') (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\": \"80.0.3987.137\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"113333b8-2ee7-4a2e-8aad-dd549b19396e\", \"title\": \"publish_tarball/0\"}]}, \"bot_id\": \"luci-infra-cron-xenial-large-0-id32\", \"buildername\": \"Build From Tarball\", \"path_config\": \"generic\", \"recipe\": \"build_from_tarball\", \"version\": \"80.0.3987.137\"}, \"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-id32\"], \"image\": [\"chrome-xenial-20030800-300841f894e\"], \"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\": [\"4851-268d16b\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-b\"]}}, \"ui\": {\"info\": \"Step('Build chrome.') (retcode: 1)\"}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1583800317576382",
"status": "COMPLETED",
"status_changed_ts": "1583800317947082",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9051693564096997088",
"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/8886263502010514000/+/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:4ada66d777af3c10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1583803300105004",
"url": "https://ci.chromium.org/b/8886263502010514000",
"utcnow_ts": "1583803300523274"
}
}
[01:21:40.536] Invocation finished in 2h5m50.387757854s with status FAILED