[19:37:50.795] New invocation is queued and will start shortly
[19:37:50.795] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[19:37:51.895] Starting the invocation (attempt 1)
[19:37:51.929] Ignoring gitiles_ref tag without the buildset tag
[19:37:51.929] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:37:51.929] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:37:51.929] Buildbucket request:
{
"requestId": "8976330602765518320",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8976330602765518320",
"job": "infra/publish_tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "105.0.5129.0"
},
"tags": [
"parent_buildername:publish_tarball_dispatcher",
"user_agent:recipe"
]
},
"id": "322648a1-2fa1-4641-82f4-9a3954ad9efd",
"title": "publish_tarball_dispatcher/0"
}
]
},
"version": "105.0.5129.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8976330602765518320"
},
{
"key": "scheduler_job_id",
"value": "infra/publish_tarball"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "publish_tarball_dispatcher"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:37:52.332] Scheduled build:
{
"id": "8810900540834284401",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"createdBy": "project:infra",
"createTime": "2022-06-19T19:37:52.004345363Z",
"updateTime": "2022-06-19T19:37:52.004345363Z",
"status": "SCHEDULED",
"input": {
}
}
[19:37:52.332] Task URL: https://cr-buildbucket.appspot.com/build/8810900540834284401
[19:37:52.332] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:2:0) after 3m56s
[19:38:08.548] Received PubSub notification, asking Buildbucket for the build status
[19:38:08.579] Build status: STARTED
[19:41:48.348] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:2:0)
[19:41:48.375] Build status: STARTED
[19:41:48.375] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:4:0) after 1m51s
[19:43:39.391] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:4:0)
[19:43:39.420] Build status: STARTED
[19:43:39.420] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:5:0) after 7m11s
[19:50:50.649] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:5:0)
[19:50:50.687] Build status: STARTED
[19:50:50.687] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:6:0) after 6m49s
[19:57:39.729] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:6:0)
[19:57:39.763] Build status: STARTED
[19:57:39.763] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:7:0) after 9m5s
[20:06:44.891] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:7:0)
[20:06:44.919] Build status: STARTED
[20:06:44.919] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:8:0) after 5m15s
[20:12:00.175] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:8:0)
[20:12:00.196] Build status: STARTED
[20:12:00.196] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:9:0) after 5m44s
[20:17:44.757] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:9:0)
[20:17:44.789] Build status: STARTED
[20:17:44.789] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:10:0) after 4m37s
[20:22:21.804] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:10:0)
[20:22:21.840] Build status: STARTED
[20:22:21.840] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:11:0) after 7m8s
[20:29:29.955] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:11:0)
[20:29:30.008] Build status: STARTED
[20:29:30.008] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:12:0) after 4m0s
[20:33:30.521] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:12:0)
[20:33:30.551] Build status: STARTED
[20:33:30.551] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:13:0) after 9m51s
[20:43:21.629] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:13:0)
[20:43:21.670] Build status: STARTED
[20:43:21.670] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:14:0) after 6m9s
[20:49:30.800] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:14:0)
[20:49:30.821] Build status: STARTED
[20:49:30.821] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:15:0) after 7m32s
[20:57:02.856] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:15:0)
[20:57:02.885] Build status: STARTED
[20:57:02.885] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:16:0) after 8m58s
[21:06:01.084] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:16:0)
[21:06:01.112] Build status: STARTED
[21:06:01.112] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:17:0) after 5m21s
[21:11:22.212] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:17:0)
[21:11:22.246] Build status: STARTED
[21:11:22.246] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:18:0) after 5m40s
[21:17:02.264] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:18:0)
[21:17:02.296] Build status: STARTED
[21:17:02.296] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:19:0) after 9m59s
[21:27:01.805] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:19:0)
[21:27:01.850] Build status: STARTED
[21:27:01.850] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:20:0) after 3m57s
[21:30:59.862] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:20:0)
[21:30:59.880] Build status: STARTED
[21:30:59.880] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:21:0) after 9m40s
[21:40:40.363] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:21:0)
[21:40:40.389] Build status: STARTED
[21:40:40.389] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:22:0) after 9m13s
[21:49:53.426] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:22:0)
[21:49:53.460] Build status: STARTED
[21:49:53.460] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:23:0) after 2m33s
[21:52:26.553] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:23:0)
[21:52:26.590] Build status: STARTED
[21:52:26.590] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:24:0) after 7m1s
[21:59:27.782] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:24:0)
[21:59:27.816] Build status: STARTED
[21:59:27.816] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:25:0) after 6m2s
[22:05:29.834] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:25:0)
[22:05:29.869] Build status: STARTED
[22:05:29.869] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:26:0) after 7m19s
[22:12:48.887] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:26:0)
[22:12:48.921] Build status: STARTED
[22:12:48.921] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:27:0) after 4m51s
[22:17:39.925] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:27:0)
[22:17:39.959] Build status: STARTED
[22:17:39.959] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:28:0) after 6m26s
[22:24:06.148] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:28:0)
[22:24:06.177] Build status: STARTED
[22:24:06.177] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:29:0) after 9m54s
[22:34:00.195] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:29:0)
[22:34:00.229] Build status: STARTED
[22:34:00.229] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:30:0) after 9m39s
[22:43:39.437] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:30:0)
[22:43:39.477] Build status: STARTED
[22:43:39.477] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:31:0) after 8m27s
[22:52:06.496] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:31:0)
[22:52:06.529] Build status: STARTED
[22:52:06.529] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:32:0) after 5m58s
[22:58:04.774] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:32:0)
[22:58:04.804] Build status: STARTED
[22:58:04.804] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:33:0) after 9m38s
[23:07:43.733] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:33:0)
[23:07:43.769] Build status: STARTED
[23:07:43.769] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:34:0) after 1m36s
[23:09:19.788] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:34:0)
[23:09:19.820] Build status: STARTED
[23:09:19.820] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:35:0) after 3m2s
[23:12:21.930] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:35:0)
[23:12:21.971] Build status: STARTED
[23:12:21.971] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:36:0) after 1m50s
[23:14:11.990] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:36:0)
[23:14:12.037] Build status: STARTED
[23:14:12.037] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:37:0) after 9m22s
[23:23:34.057] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:37:0)
[23:23:34.083] Build status: STARTED
[23:23:34.083] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:38:0) after 1m23s
[23:24:57.113] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:38:0)
[23:24:57.164] Build status: STARTED
[23:24:57.164] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:39:0) after 8m54s
[23:33:51.869] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:39:0)
[23:33:51.901] Build status: STARTED
[23:33:51.901] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:40:0) after 2m59s
[23:36:50.920] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:40:0)
[23:36:51.039] Build status: STARTED
[23:36:51.039] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:41:0) after 4m56s
[23:41:47.371] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:41:0)
[23:41:47.400] Build status: STARTED
[23:41:47.400] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:42:0) after 9m4s
[23:50:51.374] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:42:0)
[23:50:51.405] Build status: STARTED
[23:50:51.405] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:43:0) after 2m22s
[23:53:13.509] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:43:0)
[23:53:13.539] Build status: STARTED
[23:53:13.539] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:44:0) after 6m36s
[23:59:49.722] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:44:0)
[23:59:49.752] Build status: STARTED
[23:59:49.752] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:45:0) after 7m40s
[00:07:29.886] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:45:0)
[00:07:29.919] Build status: STARTED
[00:07:29.919] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:46:0) after 7m20s
[00:14:49.942] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:46:0)
[00:14:49.975] Build status: STARTED
[00:14:49.975] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:47:0) after 7m22s
[00:22:12.035] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:47:0)
[00:22:12.065] Build status: STARTED
[00:22:12.065] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:48:0) after 1m8s
[00:23:20.086] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:48:0)
[00:23:20.136] Build status: STARTED
[00:23:20.136] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:49:0) after 9m30s
[00:32:50.357] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:49:0)
[00:32:50.383] Build status: STARTED
[00:32:50.384] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:50:0) after 7m1s
[00:39:51.493] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:50:0)
[00:39:51.522] Build status: STARTED
[00:39:51.522] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:51:0) after 5m53s
[00:45:44.643] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:51:0)
[00:45:44.673] Build status: STARTED
[00:45:44.673] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:52:0) after 7m5s
[00:52:50.094] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:52:0)
[00:52:50.125] Build status: STARTED
[00:52:50.125] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:53:0) after 7m7s
[00:59:57.145] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:53:0)
[00:59:57.172] Build status: STARTED
[00:59:57.172] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:54:0) after 1m23s
[01:01:20.199] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:54:0)
[01:01:20.241] Build status: STARTED
[01:01:20.241] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:55:0) after 6m31s
[01:07:51.267] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:55:0)
[01:07:51.305] Build status: STARTED
[01:07:51.305] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:56:0) after 8m44s
[01:16:35.405] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:56:0)
[01:16:35.474] Build status: STARTED
[01:16:35.475] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:57:0) after 1m57s
[01:18:32.709] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:57:0)
[01:18:32.771] Build status: STARTED
[01:18:32.771] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:58:0) after 9m19s
[01:27:51.791] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:58:0)
[01:27:51.832] Build status: STARTED
[01:27:51.832] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:59:0) after 4m8s
[01:32:00.723] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:59:0)
[01:32:00.756] Build status: STARTED
[01:32:00.756] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:60:0) after 7m17s
[01:39:17.783] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:60:0)
[01:39:17.804] Build status: STARTED
[01:39:17.804] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8976330602765518320:61:0) after 8m6s
[01:40:11.354] Received PubSub notification, asking Buildbucket for the build status
[01:40:11.390] Build:
{
"id": "8810900540834284401",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"createdBy": "project:infra",
"createTime": "2022-06-19T19:37:52.004345363Z",
"startTime": "2022-06-19T19:38:07.590263Z",
"endTime": "2022-06-20T01:40:11.091406998Z",
"updateTime": "2022-06-20T01:40:11.091406998Z",
"status": "SUCCESS",
"input": {
}
}
[01:40:11.390] Invocation finished in 6h2m20.610277786s with status SUCCEEDED