[00:38:13.442] New invocation is queued and will start shortly
[00:38:13.442] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[00:38:14.770] Starting the invocation (attempt 1)
[00:38:14.827] Ignoring gitiles_ref tag without the buildset tag
[00:38:14.827] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:38:14.827] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:38:14.828] Buildbucket request:
{
"requestId": "8916517706817787392",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8916517706817787392",
"job": "infra/publish_tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "125.0.6409.3"
},
"tags": [
"parent_buildername:publish_tarball_dispatcher",
"user_agent:recipe"
]
},
"id": "4ce5a6d7-7b10-4059-9ea5-7f9c78c14729",
"title": "publish_tarball_dispatcher/0"
}
]
},
"version": "125.0.6409.3"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8916517706817787392"
},
{
"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"
}
}
[00:38:15.565] Scheduled build:
{
"id": "8751087644455604753",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"createdBy": "project:infra",
"createTime": "2024-04-10T00:38:15.070292971Z",
"updateTime": "2024-04-10T00:38:15.070292971Z",
"status": "SCHEDULED",
"input": {
}
}
[00:38:15.565] Task URL: https://cr-buildbucket.appspot.com/build/8751087644455604753
[00:38:15.565] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:2:0) after 6m50s
[00:38:15.651] Received PubSub notification, asking Buildbucket for the build status
[00:38:15.719] Build status: SCHEDULED
[00:38:26.913] Received PubSub notification, asking Buildbucket for the build status
[00:38:26.942] Build status: STARTED
[00:45:05.609] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:2:0)
[00:45:05.731] Build status: STARTED
[00:45:05.731] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:5:0) after 2m55s
[00:48:00.753] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:5:0)
[00:48:00.792] Build status: STARTED
[00:48:00.792] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:6:0) after 7m10s
[00:55:10.819] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:6:0)
[00:55:10.864] Build status: STARTED
[00:55:10.864] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:7:0) after 2m26s
[00:57:37.134] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:7:0)
[00:57:37.219] Build status: STARTED
[00:57:37.219] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:8:0) after 5m11s
[01:02:48.821] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:8:0)
[01:02:48.885] Build status: STARTED
[01:02:48.885] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:9:0) after 7m40s
[01:10:28.954] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:9:0)
[01:10:28.981] Build status: STARTED
[01:10:28.981] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:10:0) after 7m10s
[01:17:39.047] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:10:0)
[01:17:39.092] Build status: STARTED
[01:17:39.092] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:11:0) after 1m48s
[01:19:27.109] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:11:0)
[01:19:27.248] Build status: STARTED
[01:19:27.248] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:12:0) after 6m41s
[01:26:08.265] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:12:0)
[01:26:08.297] Build status: STARTED
[01:26:08.298] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:13:0) after 3m23s
[01:29:31.374] Handling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:13:0)
[01:29:31.401] Build status: STARTED
[01:29:31.401] Scheduling timer "check-buildbucket-build-status" (infra/publish_tarball:8916517706817787392:14:0) after 4m22s
[01:29:53.136] Received PubSub notification, asking Buildbucket for the build status
[01:29:53.165] Build:
{
"id": "8751087644455604753",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "publish_tarball"
},
"createdBy": "project:infra",
"createTime": "2024-04-10T00:38:15.070292971Z",
"startTime": "2024-04-10T00:38:23.683028180Z",
"endTime": "2024-04-10T01:29:52.878246878Z",
"updateTime": "2024-04-10T01:29:52.878246878Z",
"status": "SUCCESS",
"input": {
}
}
[01:29:53.165] Invocation finished in 51m39.730984141s with status SUCCEEDED