[03:45:22.096] New invocation is queued and will start shortly
[03:45:22.096] Triggered by user:chromium-tarball-builder@chops-service-accounts.iam.gserviceaccount.com
[03:45:23.181] Starting the invocation (attempt 1)
[03:45:23.205] Ignoring gitiles_ref tag without the buildset tag
[03:45:23.205] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:45:23.205] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:45:23.205] Buildbucket request:
{
"requestId": "8935621892632480848",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935621892632480848",
"job": "infra/Build From Tarball",
"triggers": [
{
"buildbucket": {
"properties": {
"version": "116.0.5845.182"
},
"tags": [
"parent_buildername:publish_tarball",
"user_agent:recipe"
]
},
"id": "495f3da8-47a7-4bd9-9b06-2d8f4016d2e3",
"title": "publish_tarball/0"
}
]
},
"version": "116.0.5845.182"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935621892632480848"
},
{
"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"
}
}
[03:45:23.719] Scheduled build:
{
"id": "8770191830743107601",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-09-12T03:45:23.272558178Z",
"updateTime": "2023-09-12T03:45:23.272558178Z",
"status": "SCHEDULED",
"input": {
}
}
[03:45:23.719] Task URL: https://cr-buildbucket.appspot.com/build/8770191830743107601
[03:45:23.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:2:0) after 3m14s
[03:45:24.765] Received PubSub notification, asking Buildbucket for the build status
[03:45:24.790] Build status: SCHEDULED
[03:45:25.490] Received PubSub notification, asking Buildbucket for the build status
[03:45:25.530] Build status: STARTED
[03:48:37.990] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:2:0)
[03:48:38.018] Build status: STARTED
[03:48:38.018] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:5:0) after 1m53s
[03:50:31.182] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:5:0)
[03:50:31.217] Build status: STARTED
[03:50:31.217] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:6:0) after 3m11s
[03:53:42.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:6:0)
[03:53:42.391] Build status: STARTED
[03:53:42.391] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:7:0) after 3m16s
[03:56:58.561] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:7:0)
[03:56:58.640] Build status: STARTED
[03:56:58.640] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:8:0) after 6m3s
[04:03:01.929] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:8:0)
[04:03:01.947] Build status: STARTED
[04:03:01.947] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:9:0) after 6m49s
[04:09:50.998] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:9:0)
[04:09:51.014] Build status: STARTED
[04:09:51.014] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:10:0) after 5m52s
[04:15:43.461] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:10:0)
[04:15:43.479] Build status: STARTED
[04:15:43.479] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:11:0) after 8m39s
[04:24:22.497] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:11:0)
[04:24:22.514] Build status: STARTED
[04:24:22.514] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:12:0) after 2m15s
[04:26:37.580] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:12:0)
[04:26:37.597] Build status: STARTED
[04:26:37.597] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:13:0) after 2m1s
[04:28:38.659] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:13:0)
[04:28:38.688] Build status: STARTED
[04:28:38.688] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:14:0) after 4m26s
[04:33:04.702] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:14:0)
[04:33:04.719] Build status: STARTED
[04:33:04.719] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:15:0) after 7m44s
[04:40:48.733] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:15:0)
[04:40:48.760] Build status: STARTED
[04:40:48.760] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:16:0) after 6m21s
[04:47:09.823] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:16:0)
[04:47:09.847] Build status: STARTED
[04:47:09.847] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:17:0) after 1m29s
[04:48:38.864] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:17:0)
[04:48:38.879] Build status: STARTED
[04:48:38.879] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:18:0) after 3m9s
[04:51:47.871] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:18:0)
[04:51:47.888] Build status: STARTED
[04:51:47.888] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:19:0) after 6m55s
[04:58:42.916] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:19:0)
[04:58:42.945] Build status: STARTED
[04:58:42.945] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:20:0) after 7m42s
[05:06:24.977] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:20:0)
[05:06:24.997] Build status: STARTED
[05:06:24.997] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:21:0) after 4m24s
[05:10:49.418] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:21:0)
[05:10:49.437] Build status: STARTED
[05:10:49.437] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:22:0) after 9m18s
[05:20:07.455] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:22:0)
[05:20:07.471] Build status: STARTED
[05:20:07.471] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:23:0) after 6m18s
[05:26:25.484] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:23:0)
[05:26:25.502] Build status: STARTED
[05:26:25.502] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:24:0) after 3m50s
[05:30:15.491] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:24:0)
[05:30:15.537] Build status: STARTED
[05:30:15.537] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:25:0) after 8m5s
[05:38:20.705] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:25:0)
[05:38:20.726] Build status: STARTED
[05:38:20.726] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:26:0) after 2m7s
[05:40:27.952] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:26:0)
[05:40:27.993] Build status: STARTED
[05:40:27.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:27:0) after 2m11s
[05:42:39.131] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:27:0)
[05:42:39.190] Build status: STARTED
[05:42:39.190] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:28:0) after 9m12s
[05:51:51.222] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:28:0)
[05:51:51.241] Build status: STARTED
[05:51:51.241] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:29:0) after 5m0s
[05:56:51.496] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:29:0)
[05:56:51.518] Build status: STARTED
[05:56:51.518] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:30:0) after 8m44s
[06:05:35.903] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:30:0)
[06:05:35.953] Build status: STARTED
[06:05:35.953] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:31:0) after 1m30s
[06:07:05.981] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:31:0)
[06:07:05.998] Build status: STARTED
[06:07:05.998] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:32:0) after 2m0s
[06:09:06.489] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:32:0)
[06:09:06.509] Build status: STARTED
[06:09:06.509] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:33:0) after 4m10s
[06:13:16.657] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:33:0)
[06:13:16.674] Build status: STARTED
[06:13:16.675] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:34:0) after 9m4s
[06:22:20.722] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:34:0)
[06:22:20.745] Build status: STARTED
[06:22:20.745] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:35:0) after 8m21s
[06:30:41.768] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:35:0)
[06:30:41.788] Build status: STARTED
[06:30:41.788] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:36:0) after 6m6s
[06:36:47.802] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:36:0)
[06:36:47.923] Build status: STARTED
[06:36:47.923] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:37:0) after 4m11s
[06:40:58.944] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:37:0)
[06:40:58.960] Build status: STARTED
[06:40:58.960] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:38:0) after 9m0s
[06:49:58.926] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:38:0)
[06:49:58.951] Build status: STARTED
[06:49:58.951] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:39:0) after 3m2s
[06:53:00.967] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:39:0)
[06:53:01.115] Build status: STARTED
[06:53:01.115] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:40:0) after 8m52s
[07:01:53.309] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:40:0)
[07:01:53.327] Build status: STARTED
[07:01:53.327] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:41:0) after 3m46s
[07:05:39.459] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:41:0)
[07:05:39.545] Build status: STARTED
[07:05:39.545] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:42:0) after 4m35s
[07:10:14.662] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:42:0)
[07:10:14.807] Build status: STARTED
[07:10:14.807] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:43:0) after 9m48s
[07:20:02.908] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:43:0)
[07:20:02.980] Build status: STARTED
[07:20:02.980] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:44:0) after 3m26s
[07:23:29.033] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:44:0)
[07:23:29.055] Build status: STARTED
[07:23:29.055] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:45:0) after 5m6s
[07:28:35.251] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:45:0)
[07:28:35.272] Build status: STARTED
[07:28:35.272] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:46:0) after 5m40s
[07:34:15.562] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:46:0)
[07:34:15.579] Build status: STARTED
[07:34:15.579] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:47:0) after 1m1s
[07:35:16.855] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:47:0)
[07:35:16.883] Build status: STARTED
[07:35:16.883] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:48:0) after 5m4s
[07:40:20.965] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:48:0)
[07:40:20.993] Build status: STARTED
[07:40:20.993] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:49:0) after 6m3s
[07:46:23.984] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:49:0)
[07:46:24.024] Build status: STARTED
[07:46:24.024] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:50:0) after 1m54s
[07:48:18.081] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:50:0)
[07:48:18.104] Build status: STARTED
[07:48:18.105] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:51:0) after 3m56s
[07:52:15.133] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:51:0)
[07:52:15.163] Build status: STARTED
[07:52:15.163] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:52:0) after 6m39s
[07:58:54.361] Handling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:52:0)
[07:58:54.379] Build status: STARTED
[07:58:54.379] Scheduling timer "check-buildbucket-build-status" (infra/Build From Tarball:8935621892632480848:53:0) after 7m51s
[08:06:41.199] Received PubSub notification, asking Buildbucket for the build status
[08:06:41.226] Build:
{
"id": "8770191830743107601",
"builder": {
"project": "infra",
"bucket": "cron",
"builder": "Build From Tarball"
},
"createdBy": "project:infra",
"createTime": "2023-09-12T03:45:23.272558178Z",
"startTime": "2023-09-12T03:45:24.866059Z",
"endTime": "2023-09-12T08:06:41.015587841Z",
"updateTime": "2023-09-12T08:06:41.015587841Z",
"status": "SUCCESS",
"input": {
}
}
[08:06:41.226] Invocation finished in 4h21m19.139653294s with status SUCCEEDED