[00:36:16.598] New invocation is queued and will start shortly
[00:36:16.598] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[00:36:17.686] Starting the invocation (attempt 1)
[00:36:17.720] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:36:17.720] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:36:17.720] Buildbucket request:
{
"bucket": "luci.infra.cron",
"client_operation_id": "9017714640813300352",
"parameters_json": "{\"builder_name\":\"Build From Tarball\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"version\":\"91.0.4452.0\"},\"tags\":[\"parent_buildername:publish_tarball\",\"user_agent:recipe\"]},\"id\":\"ee852795-444c-4de6-8472-bef2e74e96c8\",\"title\":\"publish_tarball/0\"}]},\"version\":\"91.0.4452.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:9017714640813300352",
"scheduler_job_id:infra/Build From Tarball",
"user_agent:luci-scheduler",
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
}
[00:36:18.037] Buildbucket response:
{
"build": {
"bucket": "luci.infra.cron",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1616200577799256",
"id": "8852284578892137056",
"parameters_json": "{\"builder_name\": \"Build From Tarball\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"version\": \"91.0.4452.0\"}, \"tags\": [\"parent_buildername:publish_tarball\", \"user_agent:recipe\"]}, \"id\": \"ee852795-444c-4de6-8472-bef2e74e96c8\", \"title\": \"publish_tarball/0\"}]}, \"version\": \"91.0.4452.0\"}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616200577930026",
"tags": [
"builder:Build From Tarball",
"parent_buildername:publish_tarball",
"scheduler_invocation_id:9017714640813300352",
"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/8852284578892137056/+/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": "1616200577930131",
"url": "https://ci.chromium.org/b/8852284578892137056",
"utcnow_ts": "1616200578030526"
}
}
[00:36:18.037] Task URL: https://ci.chromium.org/b/8852284578892137056
[00:36:18.037] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:2:0) after 1m0s
[00:36:21.593] Received PubSub notification, asking Buildbucket for the build status
[00:36:21.659] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:18.124] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:2:0)
[00:37:18.124] Timer tick, asking Buildbucket for the build status
[00:37:18.191] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:18.192] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:4:0) after 1m0s
[00:38:18.252] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:4:0)
[00:38:18.282] Build status: STARTED
[00:38:18.282] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:5:0) after 3m54s
[00:42:12.301] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:5:0)
[00:42:12.301] Timer tick, asking Buildbucket for the build status
[00:42:12.422] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:12.422] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:6:0) after 1m0s
[00:43:12.532] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:6:0)
[00:43:12.731] Build status: STARTED
[00:43:12.731] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:7:0) after 1m27s
[00:44:39.706] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:7:0)
[00:44:39.706] Timer tick, asking Buildbucket for the build status
[00:44:39.997] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:39.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:8:0) after 1m0s
[00:45:40.006] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:8:0)
[00:45:40.006] Timer tick, asking Buildbucket for the build status
[00:45:40.102] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:40.102] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:9:0) after 1m0s
[00:46:40.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:9:0)
[00:46:40.288] Timer tick, asking Buildbucket for the build status
[00:46:40.502] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:40.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:10:0) after 1m0s
[00:47:40.494] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:10:0)
[00:47:40.494] Timer tick, asking Buildbucket for the build status
[00:47:40.574] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:40.574] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:11:0) after 1m0s
[00:48:40.591] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:11:0)
[00:48:40.647] Build status: STARTED
[00:48:40.647] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:12:0) after 1m39s
[00:50:19.628] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:12:0)
[00:50:19.628] Timer tick, asking Buildbucket for the build status
[00:50:19.698] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:19.698] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:13:0) after 1m0s
[00:51:19.757] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:13:0)
[00:51:19.785] Build status: STARTED
[00:51:19.785] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:14:0) after 9m45s
[01:01:04.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:14:0)
[01:01:04.916] Timer tick, asking Buildbucket for the build status
[01:01:05.042] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:05.042] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:15:0) after 1m0s
[01:02:05.025] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:15:0)
[01:02:05.060] Build status: STARTED
[01:02:05.060] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:16:0) after 6m18s
[01:08:23.077] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:16:0)
[01:08:23.104] Build status: STARTED
[01:08:23.104] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:17:0) after 9m29s
[01:17:52.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:17:0)
[01:17:52.162] Timer tick, asking Buildbucket for the build status
[01:17:52.255] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:52.255] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:18:0) after 1m0s
[01:18:52.249] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:18:0)
[01:18:52.249] Timer tick, asking Buildbucket for the build status
[01:18:52.417] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:52.417] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:19:0) after 1m0s
[01:19:52.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:19:0)
[01:19:52.455] Timer tick, asking Buildbucket for the build status
[01:19:52.538] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:52.538] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:20:0) after 1m0s
[01:20:52.609] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:20:0)
[01:20:52.609] Timer tick, asking Buildbucket for the build status
[01:20:52.680] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:52.680] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:21:0) after 1m0s
[01:21:52.813] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:21:0)
[01:21:52.813] Timer tick, asking Buildbucket for the build status
[01:21:53.262] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:53.262] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:22:0) after 1m0s
[01:22:53.328] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:22:0)
[01:22:53.359] Build status: STARTED
[01:22:53.359] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:23:0) after 3m19s
[01:26:12.378] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:23:0)
[01:26:12.378] Timer tick, asking Buildbucket for the build status
[01:26:12.632] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:12.632] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:24:0) after 1m0s
[01:27:12.673] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:24:0)
[01:27:12.702] Build status: STARTED
[01:27:12.702] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:25:0) after 8m22s
[01:35:34.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:25:0)
[01:35:34.845] Build status: STARTED
[01:35:34.845] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:26:0) after 6m39s
[01:42:14.003] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:26:0)
[01:42:14.031] Build status: STARTED
[01:42:14.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:27:0) after 4m57s
[01:47:11.170] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:27:0)
[01:47:11.206] Build status: STARTED
[01:47:11.206] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:28:0) after 7m32s
[01:54:43.272] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:28:0)
[01:54:43.272] Timer tick, asking Buildbucket for the build status
[01:54:43.357] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:43.357] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:29:0) after 1m0s
[01:55:43.377] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:29:0)
[01:55:43.377] Timer tick, asking Buildbucket for the build status
[01:55:43.655] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:43.655] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:30:0) after 1m0s
[01:56:43.674] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:30:0)
[01:56:43.674] Timer tick, asking Buildbucket for the build status
[01:56:43.818] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:43.818] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:31:0) after 1m0s
[01:57:43.842] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:31:0)
[01:57:43.842] Timer tick, asking Buildbucket for the build status
[01:57:44.041] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:44.041] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:32:0) after 1m0s
[01:58:44.114] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:32:0)
[01:58:44.114] Timer tick, asking Buildbucket for the build status
[01:58:44.292] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:44.293] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:33:0) after 1m0s
[01:59:44.354] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:33:0)
[01:59:44.354] Timer tick, asking Buildbucket for the build status
[01:59:44.616] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:44.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:34:0) after 1m0s
[02:00:44.636] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:34:0)
[02:00:44.636] Timer tick, asking Buildbucket for the build status
[02:00:44.719] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:44.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:35:0) after 1m0s
[02:01:44.792] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:35:0)
[02:01:44.792] Timer tick, asking Buildbucket for the build status
[02:01:44.901] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:44.901] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:36:0) after 1m0s
[02:02:45.021] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:36:0)
[02:02:45.021] Timer tick, asking Buildbucket for the build status
[02:02:45.455] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:02:45.455] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:37:0) after 1m0s
[02:03:45.473] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:37:0)
[02:03:45.514] Build status: STARTED
[02:03:45.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:38:0) after 1m15s
[02:05:00.533] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:38:0)
[02:05:00.533] Timer tick, asking Buildbucket for the build status
[02:05:03.717] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:05:03.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:39:0) after 1m0s
[02:06:03.741] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:39:0)
[02:06:03.741] Timer tick, asking Buildbucket for the build status
[02:06:03.886] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:06:03.886] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:40:0) after 1m0s
[02:07:03.907] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:40:0)
[02:07:03.939] Build status: STARTED
[02:07:03.939] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:41:0) after 8m17s
[02:15:20.956] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:41:0)
[02:15:20.956] Timer tick, asking Buildbucket for the build status
[02:15:21.297] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:21.297] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:42:0) after 1m0s
[02:16:21.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:42:0)
[02:16:21.287] Timer tick, asking Buildbucket for the build status
[02:16:21.356] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:21.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:43:0) after 1m0s
[02:17:21.431] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:43:0)
[02:17:21.431] Timer tick, asking Buildbucket for the build status
[02:17:21.510] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:21.510] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:44:0) after 1m0s
[02:18:21.540] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:44:0)
[02:18:21.540] Timer tick, asking Buildbucket for the build status
[02:18:21.619] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:21.619] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:45:0) after 1m0s
[02:19:21.679] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:45:0)
[02:19:21.717] Build status: STARTED
[02:19:21.717] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:46:0) after 3m45s
[02:23:06.771] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:46:0)
[02:23:06.771] Timer tick, asking Buildbucket for the build status
[02:23:06.970] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:06.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:47:0) after 1m0s
[02:24:06.986] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:47:0)
[02:24:07.023] Build status: STARTED
[02:24:07.023] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:48:0) after 6m12s
[02:30:19.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:48:0)
[02:30:19.067] Build status: STARTED
[02:30:19.067] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:49:0) after 8m20s
[02:38:39.154] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:49:0)
[02:38:39.154] Timer tick, asking Buildbucket for the build status
[02:38:39.529] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:39.529] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:50:0) after 1m0s
[02:39:39.547] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:50:0)
[02:39:39.580] Build status: STARTED
[02:39:39.580] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:51:0) after 2m19s
[02:41:58.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:51:0)
[02:41:58.642] Timer tick, asking Buildbucket for the build status
[02:41:58.737] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:58.737] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:52:0) after 1m0s
[02:42:58.777] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:52:0)
[02:42:58.810] Build status: STARTED
[02:42:58.810] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:53:0) after 9m48s
[02:52:46.831] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:53:0)
[02:52:46.865] Build status: STARTED
[02:52:46.865] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:54:0) after 2m36s
[02:55:22.948] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:54:0)
[02:55:22.948] Timer tick, asking Buildbucket for the build status
[02:55:23.035] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:23.035] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:55:0) after 1m0s
[02:56:23.125] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:55:0)
[02:56:23.125] Timer tick, asking Buildbucket for the build status
[02:56:23.294] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:23.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:56:0) after 1m0s
[02:57:23.316] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:56:0)
[02:57:23.345] Build status: STARTED
[02:57:23.345] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:57:0) after 5m58s
[03:03:21.352] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:57:0)
[03:03:21.380] Build status: STARTED
[03:03:21.380] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:58:0) after 3m43s
[03:07:04.475] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:58:0)
[03:07:04.475] Timer tick, asking Buildbucket for the build status
[03:07:04.566] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:04.566] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:59:0) after 1m0s
[03:08:04.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:59:0)
[03:08:04.621] Build status: STARTED
[03:08:04.621] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:60:0) after 8m59s
[03:17:03.736] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:60:0)
[03:17:03.760] Build status: STARTED
[03:17:03.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:61:0) after 8m57s
[03:26:00.787] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:61:0)
[03:26:00.816] Build status: STARTED
[03:26:00.816] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:62:0) after 8m24s
[03:34:25.073] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:62:0)
[03:34:25.073] Timer tick, asking Buildbucket for the build status
[03:34:25.199] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:25.199] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:63:0) after 1m0s
[03:35:25.261] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:63:0)
[03:35:25.261] Timer tick, asking Buildbucket for the build status
[03:35:25.326] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:25.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:64:0) after 1m0s
[03:36:25.320] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:64:0)
[03:36:25.320] Timer tick, asking Buildbucket for the build status
[03:36:25.672] Build 8852284578892137056: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:25.672] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:65:0) after 1m0s
[03:37:25.723] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:65:0)
[03:37:25.751] Build status: STARTED
[03:37:25.751] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:9017714640813300352:66:0) after 3m59s
[03:39:43.288] Received PubSub notification, asking Buildbucket for the build status
[03:39:43.314] Build:
{
"id": "8852284578892137056",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2021-03-20T00:36:17.799256Z",
"startTime": "2021-03-20T00:36:21.066363Z",
"endTime": "2021-03-20T03:39:42.990247315Z",
"updateTime": "2021-03-20T03:39:42.990654545Z",
"status": "SUCCESS",
"input": {
}
}
[03:39:43.314] Invocation finished in 3h3m26.730557251s with status SUCCEEDED