[07:01:11.527] New invocation is queued and will start shortly
[07:01:11.527] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[07:01:12.689] Starting the invocation (attempt 1)
[07:01:12.716] Ignoring gitiles_ref tag without the buildset tag
[07:01:12.716] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:01:12.716] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:01:12.716] Buildbucket request:
{
"requestId": "8945303447864882608",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8945303447864882608",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "116.0.5797.0"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "25520ff1-444c-4fb5-b92d-7a2955d66522",
"title": "publish_tarball/0"
}
]
},
"version": "116.0.5797.0"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8945303447864882608"
},
{
"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"
}
}
[07:01:13.136] Scheduled build:
{
"id": "8779873385871109105",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-05-28T07:01:12.803447995Z",
"updateTime": "2023-05-28T07:01:12.803447995Z",
"status": "SCHEDULED",
"input": {
}
}
[07:01:13.136] Task URL: https://cr-buildbucket.appspot.com/build/8779873385871109105
[07:01:13.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:2:0) after 4m29s
[07:05:12.152] Received PubSub notification, asking Buildbucket for the build status
[07:05:12.170] Build status: STARTED
[07:05:42.151] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:2:0)
[07:05:42.227] Build status: STARTED
[07:05:42.227] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:4:0) after 3m43s
[07:09:25.346] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:4:0)
[07:09:25.462] Build status: STARTED
[07:09:25.462] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:5:0) after 5m19s
[07:14:44.479] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:5:0)
[07:14:44.497] Build status: STARTED
[07:14:44.497] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:6:0) after 1m51s
[07:16:35.511] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:6:0)
[07:16:35.534] Build status: STARTED
[07:16:35.534] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:7:0) after 5m26s
[07:22:01.721] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:7:0)
[07:22:01.805] Build status: STARTED
[07:22:01.805] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:8:0) after 8m37s
[07:30:38.818] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:8:0)
[07:30:38.840] Build status: STARTED
[07:30:38.840] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:9:0) after 6m40s
[07:37:18.985] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:9:0)
[07:37:19.136] Build status: STARTED
[07:37:19.136] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:10:0) after 5m4s
[07:42:23.248] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:10:0)
[07:42:23.268] Build status: STARTED
[07:42:23.268] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:11:0) after 3m21s
[07:45:44.292] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:11:0)
[07:45:44.339] Build status: STARTED
[07:45:44.339] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:12:0) after 5m30s
[07:51:14.427] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:12:0)
[07:51:14.473] Build status: STARTED
[07:51:14.474] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:13:0) after 4m33s
[07:55:47.470] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:13:0)
[07:55:47.489] Build status: STARTED
[07:55:47.489] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:14:0) after 7m46s
[08:03:33.979] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:14:0)
[08:03:34.011] Build status: STARTED
[08:03:34.011] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:15:0) after 9m23s
[08:12:57.034] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:15:0)
[08:12:57.061] Build status: STARTED
[08:12:57.061] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:16:0) after 3m3s
[08:16:01.162] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:16:0)
[08:16:01.182] Build status: STARTED
[08:16:01.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:17:0) after 8m45s
[08:24:46.817] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:17:0)
[08:24:46.842] Build status: STARTED
[08:24:46.842] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:18:0) after 3m11s
[08:27:58.098] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:18:0)
[08:27:58.130] Build status: STARTED
[08:27:58.130] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:19:0) after 7m51s
[08:35:49.147] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:19:0)
[08:35:49.185] Build status: STARTED
[08:35:49.185] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:20:0) after 4m38s
[08:40:27.216] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:20:0)
[08:40:27.254] Build status: STARTED
[08:40:27.254] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:21:0) after 6m46s
[08:47:13.288] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:21:0)
[08:47:13.353] Build status: STARTED
[08:47:13.353] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:22:0) after 3m21s
[08:50:34.369] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:22:0)
[08:50:34.496] Build status: STARTED
[08:50:34.496] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:23:0) after 1m3s
[08:51:37.487] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:23:0)
[08:51:37.519] Build status: STARTED
[08:51:37.519] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:24:0) after 6m34s
[08:58:11.526] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:24:0)
[08:58:11.554] Build status: STARTED
[08:58:11.555] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:25:0) after 9m29s
[09:07:40.593] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:25:0)
[09:07:40.613] Build status: STARTED
[09:07:40.613] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:26:0) after 8m52s
[09:16:32.652] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:26:0)
[09:16:32.674] Build status: STARTED
[09:16:32.674] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:27:0) after 4m42s
[09:21:14.711] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:27:0)
[09:21:14.738] Build status: STARTED
[09:21:14.738] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:28:0) after 2m52s
[09:24:06.753] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:28:0)
[09:24:06.786] Build status: STARTED
[09:24:06.787] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:29:0) after 8m10s
[09:32:16.806] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:29:0)
[09:32:16.831] Build status: STARTED
[09:32:16.831] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:30:0) after 6m50s
[09:39:06.898] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:30:0)
[09:39:06.931] Build status: STARTED
[09:39:06.931] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:31:0) after 9m45s
[09:48:51.950] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:31:0)
[09:48:51.991] Build status: STARTED
[09:48:51.991] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:32:0) after 4m50s
[09:53:42.153] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:32:0)
[09:53:42.182] Build status: STARTED
[09:53:42.182] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:33:0) after 7m47s
[10:01:29.295] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:33:0)
[10:01:29.324] Build status: STARTED
[10:01:29.324] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:34:0) after 6m5s
[10:07:34.383] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:34:0)
[10:07:34.491] Build status: STARTED
[10:07:34.491] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:35:0) after 4m33s
[10:12:07.515] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:35:0)
[10:12:07.530] Build status: STARTED
[10:12:07.530] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:36:0) after 4m40s
[10:16:47.544] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:36:0)
[10:16:47.612] Build status: STARTED
[10:16:47.612] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:37:0) after 7m57s
[10:24:44.629] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:37:0)
[10:24:44.646] Build status: STARTED
[10:24:44.646] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:38:0) after 4m42s
[10:29:26.663] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:38:0)
[10:29:26.691] Build status: STARTED
[10:29:26.691] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:39:0) after 5m20s
[10:34:47.682] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:39:0)
[10:34:47.745] Build status: STARTED
[10:34:47.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:40:0) after 5m1s
[10:39:49.239] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:40:0)
[10:39:49.263] Build status: STARTED
[10:39:49.263] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:41:0) after 6m6s
[10:45:55.287] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:41:0)
[10:45:55.319] Build status: STARTED
[10:45:55.319] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:42:0) after 6m22s
[10:52:17.585] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:42:0)
[10:52:17.616] Build status: STARTED
[10:52:17.616] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:43:0) after 6m36s
[10:58:53.623] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:43:0)
[10:58:53.668] Build status: STARTED
[10:58:53.668] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:44:0) after 5m1s
[11:03:54.730] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:44:0)
[11:03:54.766] Build status: STARTED
[11:03:54.766] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8945303447864882608:45:0) after 4m1s
[11:05:59.042] Received PubSub notification, asking Buildbucket for the build status
[11:05:59.083] Build:
{
"id": "8779873385871109105",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-05-28T07:01:12.803447995Z",
"startTime": "2023-05-28T07:05:11.519368Z",
"endTime": "2023-05-28T11:05:58.822424841Z",
"updateTime": "2023-05-28T11:05:58.822424841Z",
"status": "SUCCESS",
"input": {
}
}
[11:05:59.084] Invocation finished in 4h4m47.565253156s with status SUCCEEDED