[08:12:44.290] New invocation is queued and will start shortly
[08:12:44.290] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[08:12:45.368] Starting the invocation (attempt 1)
[08:12:45.414] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:12:45.415] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:12:45.415] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9017685922737472224",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"91.0.4453.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"0f4478c2-2f2b-4c30-9d97-f77510066c12\",\"title\":\"publish_tarball/0\"}]},\"version\":\"91.0.4453.0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Build From Tarball",
"scheduler_invocation_id:9017685922737472224",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[08:12:46.023] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1616227965553999",
"id": "8852255860750788096",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4453.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"0f4478c2-2f2b-4c30-9d97-f77510066c12\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4453.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616227965779038",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9017685922737472224",
"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/8852255860750788096/+/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": "1616227965779167",
"url": "https://ci.chromium.org/b/8852255860750788096",
"utcnow_ts": "1616227966015325"
}
}
[08:12:46.023] Task URL: https://ci.chromium.org/b/8852255860750788096
[08:12:46.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:2:0) after 1m0s
[08:12:51.008] Received PubSub notification, asking Buildbucket for the build status
[08:12:51.039] Build status: STARTED
[08:13:46.038] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:2:0)
[08:13:46.038] Timer tick, asking Buildbucket for the build status
[08:13:46.100] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:46.100] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:4:0) after 1m0s
[08:14:46.237] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:4:0)
[08:14:46.237] Timer tick, asking Buildbucket for the build status
[08:14:46.304] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:46.304] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:5:0) after 1m0s
[08:15:46.401] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:5:0)
[08:15:46.401] Timer tick, asking Buildbucket for the build status
[08:15:46.466] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:46.466] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:6:0) after 1m0s
[08:16:46.543] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:6:0)
[08:16:46.568] Build status: STARTED
[08:16:46.568] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:7:0) after 3m47s
[08:20:33.685] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:7:0)
[08:20:33.718] Build status: STARTED
[08:20:33.718] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:8:0) after 3m40s
[08:24:13.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:8:0)
[08:24:13.950] Timer tick, asking Buildbucket for the build status
[08:24:14.018] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:14.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:9:0) after 1m0s
[08:25:14.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:9:0)
[08:25:14.131] Timer tick, asking Buildbucket for the build status
[08:25:14.214] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:14.214] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:10:0) after 1m0s
[08:26:14.329] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:10:0)
[08:26:14.329] Timer tick, asking Buildbucket for the build status
[08:26:14.427] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:14.427] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:11:0) after 1m0s
[08:27:14.444] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:11:0)
[08:27:14.444] Timer tick, asking Buildbucket for the build status
[08:27:14.597] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:14.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:12:0) after 1m0s
[08:28:14.719] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:12:0)
[08:28:14.745] Build status: STARTED
[08:28:14.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:13:0) after 8m37s
[08:36:51.860] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:13:0)
[08:36:51.860] Timer tick, asking Buildbucket for the build status
[08:36:51.941] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:51.941] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:14:0) after 1m0s
[08:37:52.016] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:14:0)
[08:37:52.043] Build status: STARTED
[08:37:52.043] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:15:0) after 8m5s
[08:45:57.061] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:15:0)
[08:45:57.092] Build status: STARTED
[08:45:57.092] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:16:0) after 1m3s
[08:47:00.126] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:16:0)
[08:47:00.153] Build status: STARTED
[08:47:00.153] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:17:0) after 1m4s
[08:48:04.999] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:17:0)
[08:48:05.034] Build status: STARTED
[08:48:05.034] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:18:0) after 4m21s
[08:52:26.068] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:18:0)
[08:52:26.068] Timer tick, asking Buildbucket for the build status
[08:52:26.431] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:26.431] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:19:0) after 1m0s
[08:53:26.649] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:19:0)
[08:53:26.784] Build status: STARTED
[08:53:26.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:20:0) after 4m2s
[08:57:28.893] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:20:0)
[08:57:28.893] Timer tick, asking Buildbucket for the build status
[08:57:28.971] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:28.971] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:21:0) after 1m0s
[08:58:28.988] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:21:0)
[08:58:29.028] Build status: STARTED
[08:58:29.028] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:22:0) after 8m21s
[09:06:50.129] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:22:0)
[09:06:50.156] Build status: STARTED
[09:06:50.156] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:23:0) after 6m2s
[09:12:52.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:23:0)
[09:12:52.300] Build status: STARTED
[09:12:52.300] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:24:0) after 1m23s
[09:14:15.381] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:24:0)
[09:14:15.381] Timer tick, asking Buildbucket for the build status
[09:14:15.462] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:15.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:25:0) after 1m0s
[09:15:15.692] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:25:0)
[09:15:15.743] Build status: STARTED
[09:15:15.743] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:26:0) after 2m40s
[09:17:55.761] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:26:0)
[09:17:55.786] Build status: STARTED
[09:17:55.786] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:27:0) after 8m21s
[09:26:16.794] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:27:0)
[09:26:16.794] Timer tick, asking Buildbucket for the build status
[09:26:17.004] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:17.004] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:28:0) after 1m0s
[09:27:17.051] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:28:0)
[09:27:17.083] Build status: STARTED
[09:27:17.083] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:29:0) after 5m21s
[09:32:38.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:29:0)
[09:32:38.189] Build status: STARTED
[09:32:38.189] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:30:0) after 5m22s
[09:38:00.165] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:30:0)
[09:38:00.165] Timer tick, asking Buildbucket for the build status
[09:38:00.365] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:00.365] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:31:0) after 1m0s
[09:39:00.380] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:31:0)
[09:39:00.407] Build status: STARTED
[09:39:00.407] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:32:0) after 1m57s
[09:40:57.424] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:32:0)
[09:40:57.424] Timer tick, asking Buildbucket for the build status
[09:40:57.517] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:57.517] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:33:0) after 1m0s
[09:41:57.602] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:33:0)
[09:41:57.602] Timer tick, asking Buildbucket for the build status
[09:41:57.684] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:57.684] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:34:0) after 1m0s
[09:42:57.943] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:34:0)
[09:42:57.969] Build status: STARTED
[09:42:57.969] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:35:0) after 4m34s
[09:47:32.079] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:35:0)
[09:47:32.079] Timer tick, asking Buildbucket for the build status
[09:47:32.184] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:32.184] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:36:0) after 1m0s
[09:48:32.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:36:0)
[09:48:32.460] Build status: STARTED
[09:48:32.460] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:37:0) after 2m23s
[09:50:55.669] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:37:0)
[09:50:55.711] Build status: STARTED
[09:50:55.711] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:38:0) after 3m32s
[09:54:27.760] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:38:0)
[09:54:27.784] Build status: STARTED
[09:54:27.784] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:39:0) after 3m38s
[09:58:06.040] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:39:0)
[09:58:06.040] Timer tick, asking Buildbucket for the build status
[09:58:06.129] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:06.129] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:40:0) after 1m0s
[09:59:06.146] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:40:0)
[09:59:06.146] Timer tick, asking Buildbucket for the build status
[09:59:06.223] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:06.223] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:41:0) after 1m0s
[10:00:06.271] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:41:0)
[10:00:06.328] Build status: STARTED
[10:00:06.328] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:42:0) after 3m43s
[10:03:49.480] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:42:0)
[10:03:49.480] Timer tick, asking Buildbucket for the build status
[10:03:49.620] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:49.620] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:43:0) after 1m0s
[10:04:49.647] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:43:0)
[10:04:49.647] Timer tick, asking Buildbucket for the build status
[10:04:49.730] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:49.730] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:44:0) after 1m0s
[10:05:49.846] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:44:0)
[10:05:49.846] Timer tick, asking Buildbucket for the build status
[10:05:50.025] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:50.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:45:0) after 1m0s
[10:06:50.069] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:45:0)
[10:06:50.069] Timer tick, asking Buildbucket for the build status
[10:06:50.138] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:50.138] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:46:0) after 1m0s
[10:07:50.159] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:46:0)
[10:07:50.159] Timer tick, asking Buildbucket for the build status
[10:07:50.303] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:50.303] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:47:0) after 1m0s
[10:08:50.422] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:47:0)
[10:08:50.422] Timer tick, asking Buildbucket for the build status
[10:08:50.497] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:50.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:48:0) after 1m0s
[10:09:50.519] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:48:0)
[10:09:50.547] Build status: STARTED
[10:09:50.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:49:0) after 8m20s
[10:18:10.765] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:49:0)
[10:18:10.795] Build status: STARTED
[10:18:10.795] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:50:0) after 1m56s
[10:20:06.915] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:50:0)
[10:20:06.942] Build status: STARTED
[10:20:06.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:51:0) after 4m45s
[10:24:51.961] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:51:0)
[10:24:51.961] Timer tick, asking Buildbucket for the build status
[10:24:52.187] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:52.187] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:52:0) after 1m0s
[10:25:52.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:52:0)
[10:25:52.211] Timer tick, asking Buildbucket for the build status
[10:25:52.280] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:52.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:53:0) after 1m0s
[10:26:52.460] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:53:0)
[10:26:52.460] Timer tick, asking Buildbucket for the build status
[10:26:52.547] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:52.547] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:54:0) after 1m0s
[10:27:52.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:54:0)
[10:27:52.628] Timer tick, asking Buildbucket for the build status
[10:27:52.689] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:52.689] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:55:0) after 1m0s
[10:28:52.756] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:55:0)
[10:28:52.782] Build status: STARTED
[10:28:52.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:56:0) after 6m47s
[10:35:39.834] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:56:0)
[10:35:39.865] Build status: STARTED
[10:35:39.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:57:0) after 4m9s
[10:39:49.213] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:57:0)
[10:39:49.243] Build status: STARTED
[10:39:49.243] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:58:0) after 3m13s
[10:43:02.265] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:58:0)
[10:43:02.288] Build status: STARTED
[10:43:02.288] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:59:0) after 2m44s
[10:45:46.314] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:59:0)
[10:45:46.338] Build status: STARTED
[10:45:46.338] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:60:0) after 6m9s
[10:51:55.410] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:60:0)
[10:51:55.489] Build status: STARTED
[10:51:55.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:61:0) after 3m58s
[10:55:53.805] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:61:0)
[10:55:53.805] Timer tick, asking Buildbucket for the build status
[10:55:53.880] Build 8852255860750788096: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:53.880] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:62:0) after 1m0s
[10:56:53.897] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:62:0)
[10:56:53.924] Build status: STARTED
[10:56:53.924] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:63:0) after 6m38s
[11:03:31.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:63:0)
[11:03:31.970] Build status: STARTED
[11:03:31.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:64:0) after 2m8s
[11:05:39.994] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:64:0)
[11:05:40.025] Build status: STARTED
[11:05:40.025] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017685922737472224:65:0) after 9m12s
[11:13:48.441] Received PubSub notification, asking Buildbucket for the build status
[11:13:48.465] Build:
{
"id": "8852255860750788096",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2021-03-20T08:12:45.553999Z",
"startTime": "2021-03-20T08:12:49.958025Z",
"endTime": "2021-03-20T11:13:47.802806008Z",
"updateTime": "2021-03-20T11:13:47.803216359Z",
"status": "SUCCESS",
"input": {
}
}
[11:13:48.465] Invocation finished in 3h1m4.190439934s with status SUCCEEDED