[22:58:00.859] New invocation is queued and will start shortly
[22:58:00.859] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[22:58:01.956] Starting the invocation (attempt 1)
[22:58:01.987] Ignoring gitiles_ref tag without the buildset tag
[22:58:01.987] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:58:01.987] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:58:01.988] Buildbucket request:
{
"requestId": "8955933691859467392",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8955933691859467392",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "112.0.5569.0"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "a70af18c-1043-43c5-a15c-5c0343d960a6",
"title": "publish_tarball/0"
}
]
},
"version": "112.0.5569.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8955933691859467392"
},
{
"key": "scheduler_job_id",
"value": "infra/Build From Tarball"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "publish_tarball"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:58:02.421] Scheduled build:
{
"id": "8790503629868636897",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-01-30T22:58:02.126857914Z",
"updateTime": "2023-01-30T22:58:02.126857914Z",
"status": "SCHEDULED",
"input": {
}
}
[22:58:02.421] Task URL: https://cr-buildbucket.appspot.com/build/8790503629868636897
[22:58:02.421] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:2:0) after 2m13s
[22:58:42.347] Received PubSub notification, asking Buildbucket for the build status
[22:58:42.380] Build status: STARTED
[23:00:15.398] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:2:0)
[23:00:15.476] Build status: STARTED
[23:00:15.476] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:4:0) after 5m42s
[23:05:57.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:4:0)
[23:05:57.528] Build status: STARTED
[23:05:57.528] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:5:0) after 6m10s
[23:12:07.552] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:5:0)
[23:12:07.583] Build status: STARTED
[23:12:07.583] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:6:0) after 1m36s
[23:13:43.642] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:6:0)
[23:13:43.681] Build status: STARTED
[23:13:43.681] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:7:0) after 8m41s
[23:22:25.031] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:7:0)
[23:22:25.101] Build status: STARTED
[23:22:25.101] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:8:0) after 1m6s
[23:23:31.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:8:0)
[23:23:31.710] Build status: STARTED
[23:23:31.710] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:9:0) after 5m0s
[23:28:31.743] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:9:0)
[23:28:31.782] Build status: STARTED
[23:28:31.782] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:10:0) after 8m43s
[23:37:14.863] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:10:0)
[23:37:14.911] Build status: STARTED
[23:37:14.912] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:11:0) after 7m59s
[23:45:13.945] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:11:0)
[23:45:13.976] Build status: STARTED
[23:45:13.976] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:12:0) after 3m32s
[23:48:45.996] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:12:0)
[23:48:46.031] Build status: STARTED
[23:48:46.031] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:13:0) after 2m53s
[23:51:39.048] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:13:0)
[23:51:39.072] Build status: STARTED
[23:51:39.072] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:14:0) after 6m9s
[23:57:48.091] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:14:0)
[23:57:48.122] Build status: STARTED
[23:57:48.122] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:15:0) after 5m11s
[00:02:59.258] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:15:0)
[00:02:59.294] Build status: STARTED
[00:02:59.294] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:16:0) after 2m15s
[00:05:14.587] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:16:0)
[00:05:14.611] Build status: STARTED
[00:05:14.611] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:17:0) after 4m26s
[00:09:40.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:17:0)
[00:09:40.950] Build status: STARTED
[00:09:40.950] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:18:0) after 7m16s
[00:16:57.339] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:18:0)
[00:16:57.371] Build status: STARTED
[00:16:57.371] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:19:0) after 5m14s
[00:22:11.528] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:19:0)
[00:22:11.551] Build status: STARTED
[00:22:11.551] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:20:0) after 4m57s
[00:29:20.193] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:20:0)
[00:29:20.220] Build status: STARTED
[00:29:20.220] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:21:0) after 7m57s
[00:37:17.403] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:21:0)
[00:37:17.447] Build status: STARTED
[00:37:17.447] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:22:0) after 9m31s
[00:46:48.667] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:22:0)
[00:46:48.691] Build status: STARTED
[00:46:48.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:23:0) after 6m49s
[00:53:37.713] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:23:0)
[00:53:37.735] Build status: STARTED
[00:53:37.735] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:24:0) after 6m12s
[00:59:49.786] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:24:0)
[00:59:49.806] Build status: STARTED
[00:59:49.806] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:25:0) after 6m28s
[01:06:17.837] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:25:0)
[01:06:17.873] Build status: STARTED
[01:06:17.873] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:26:0) after 9m26s
[01:15:43.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:26:0)
[01:15:43.970] Build status: STARTED
[01:15:43.970] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:27:0) after 4m46s
[01:20:30.036] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:27:0)
[01:20:30.065] Build status: STARTED
[01:20:30.065] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:28:0) after 5m30s
[01:26:00.135] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:28:0)
[01:26:00.217] Build status: STARTED
[01:26:00.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:29:0) after 7m17s
[01:33:17.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:29:0)
[01:33:17.313] Build status: STARTED
[01:33:17.313] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:30:0) after 8m13s
[01:41:30.336] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:30:0)
[01:41:30.356] Build status: STARTED
[01:41:30.356] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:31:0) after 5m20s
[01:46:50.372] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:31:0)
[01:46:50.418] Build status: STARTED
[01:46:50.418] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:32:0) after 5m34s
[01:52:24.433] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:32:0)
[01:52:24.468] Build status: STARTED
[01:52:24.468] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:33:0) after 9m8s
[02:01:32.840] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:33:0)
[02:01:32.872] Build status: STARTED
[02:01:32.872] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:34:0) after 9m13s
[02:10:45.904] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:34:0)
[02:10:45.942] Build status: STARTED
[02:10:45.942] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:35:0) after 9m53s
[02:20:39.011] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:35:0)
[02:20:39.050] Build status: STARTED
[02:20:39.050] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:36:0) after 8m44s
[02:29:23.211] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:36:0)
[02:29:23.280] Build status: STARTED
[02:29:23.280] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:37:0) after 4m22s
[02:33:45.299] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:37:0)
[02:33:45.326] Build status: STARTED
[02:33:45.326] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8955933691859467392:38:0) after 8m5s
[02:33:51.125] Received PubSub notification, asking Buildbucket for the build status
[02:33:51.145] Build:
{
"id": "8790503629868636897",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-01-30T22:58:02.126857914Z",
"startTime": "2023-01-30T22:58:41.816197Z",
"endTime": "2023-01-31T02:33:50.944857562Z",
"updateTime": "2023-01-31T02:33:50.944857562Z",
"status": "FAILURE",
"input": {
}
}
[02:33:51.145] Invocation finished in 3h35m50.300975944s with status FAILED