[14:19:02.888] New invocation is queued and will start shortly
[14:19:03.927] Starting the invocation (attempt 1)
[14:19:03.961] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/6ff8a06de9ce125023e117014ce4dca8fcc391d7"
[14:19:03.961] Popped gitiles commit info from properties and tags
[14:19:03.961] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:19:03.961] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:19:03.962] Buildbucket request:
{
"requestId": "8888924587241792896",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888924587241792896",
"job": "fuchsia/clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "16df836a527e4a04d2cbdb52365c81ff80e3e757"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@16df836a527e4a04d2cbdb52365c81ff80e3e757",
"title": "16df836a527e4a04d2cbdb52365c81ff80e3e757",
"url": "https://llvm.googlesource.com/llvm-project/+/16df836a527e4a04d2cbdb52365c81ff80e3e757"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0cdb467c7da731bb83abc75480cbf66ad64aa014"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0cdb467c7da731bb83abc75480cbf66ad64aa014",
"title": "0cdb467c7da731bb83abc75480cbf66ad64aa014",
"url": "https://llvm.googlesource.com/llvm-project/+/0cdb467c7da731bb83abc75480cbf66ad64aa014"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ee806646ad893fcb0d19a75cebcc1f0e0bccabf1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ee806646ad893fcb0d19a75cebcc1f0e0bccabf1",
"title": "ee806646ad893fcb0d19a75cebcc1f0e0bccabf1",
"url": "https://llvm.googlesource.com/llvm-project/+/ee806646ad893fcb0d19a75cebcc1f0e0bccabf1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "66bea0df75ccdd5ffed41d06c7301a116d11abcb"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@66bea0df75ccdd5ffed41d06c7301a116d11abcb",
"title": "66bea0df75ccdd5ffed41d06c7301a116d11abcb",
"url": "https://llvm.googlesource.com/llvm-project/+/66bea0df75ccdd5ffed41d06c7301a116d11abcb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ef23ba7da34ca1285f10603cc4aa6441ab4530e6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ef23ba7da34ca1285f10603cc4aa6441ab4530e6",
"title": "ef23ba7da34ca1285f10603cc4aa6441ab4530e6",
"url": "https://llvm.googlesource.com/llvm-project/+/ef23ba7da34ca1285f10603cc4aa6441ab4530e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4e29148cca3fac0f1ffb1fbfbe3bbbd489859897"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4e29148cca3fac0f1ffb1fbfbe3bbbd489859897",
"title": "4e29148cca3fac0f1ffb1fbfbe3bbbd489859897",
"url": "https://llvm.googlesource.com/llvm-project/+/4e29148cca3fac0f1ffb1fbfbe3bbbd489859897"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "54e0c2bbe2b36b08772ca6e5e3f176d7caf116bd"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@54e0c2bbe2b36b08772ca6e5e3f176d7caf116bd",
"title": "54e0c2bbe2b36b08772ca6e5e3f176d7caf116bd",
"url": "https://llvm.googlesource.com/llvm-project/+/54e0c2bbe2b36b08772ca6e5e3f176d7caf116bd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "df2e8ee7ae349364967a1a2d09f17b249a38c04d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@df2e8ee7ae349364967a1a2d09f17b249a38c04d",
"title": "df2e8ee7ae349364967a1a2d09f17b249a38c04d",
"url": "https://llvm.googlesource.com/llvm-project/+/df2e8ee7ae349364967a1a2d09f17b249a38c04d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6ff8a06de9ce125023e117014ce4dca8fcc391d7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6ff8a06de9ce125023e117014ce4dca8fcc391d7",
"title": "6ff8a06de9ce125023e117014ce4dca8fcc391d7",
"url": "https://llvm.googlesource.com/llvm-project/+/6ff8a06de9ce125023e117014ce4dca8fcc391d7"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6ff8a06de9ce125023e117014ce4dca8fcc391d7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888924587241792896"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang-prod-unbounded-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:19:04.489] Scheduled build:
{
"id": "8723494525372584849",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-08T14:19:04.049558145Z",
"updateTime": "2025-02-08T14:19:04.049558145Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6ff8a06de9ce125023e117014ce4dca8fcc391d7",
"ref": "refs/heads/main"
}
}
}
[14:19:04.489] Task URL: https://cr-buildbucket.appspot.com/build/8723494525372584849
[14:19:04.489] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:2:0) after 7m44s
[14:19:05.477] Received PubSub notification, asking Buildbucket for the build status
[14:19:05.539] Build status: SCHEDULED
[14:19:10.703] Received PubSub notification, asking Buildbucket for the build status
[14:19:10.729] Build status: STARTED
[14:26:48.599] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:2:0)
[14:26:48.622] Build status: STARTED
[14:26:48.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:5:0) after 5m28s
[14:32:16.641] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:5:0)
[14:32:16.668] Build status: STARTED
[14:32:16.668] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:6:0) after 9m7s
[14:41:23.982] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:6:0)
[14:41:24.014] Build status: STARTED
[14:41:24.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:7:0) after 7m50s
[14:49:14.034] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:7:0)
[14:49:14.060] Build status: STARTED
[14:49:14.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:8:0) after 5m55s
[14:55:09.079] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:8:0)
[14:55:09.105] Build status: STARTED
[14:55:09.105] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:9:0) after 2m21s
[14:57:30.122] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:9:0)
[14:57:30.152] Build status: STARTED
[14:57:30.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:10:0) after 6m53s
[15:04:23.206] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:10:0)
[15:04:23.222] Build status: STARTED
[15:04:23.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:11:0) after 4m54s
[15:09:17.482] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:11:0)
[15:09:17.508] Build status: STARTED
[15:09:17.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:12:0) after 2m28s
[15:11:45.558] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:12:0)
[15:11:45.579] Build status: STARTED
[15:11:45.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:13:0) after 2m4s
[15:13:49.593] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:13:0)
[15:13:49.617] Build status: STARTED
[15:13:49.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:14:0) after 5m13s
[15:19:02.793] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:14:0)
[15:19:02.868] Build status: STARTED
[15:19:02.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:15:0) after 4m39s
[15:23:42.041] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:15:0)
[15:23:42.066] Build status: STARTED
[15:23:42.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:16:0) after 9m54s
[15:33:36.172] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:16:0)
[15:33:36.362] Build status: STARTED
[15:33:36.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:17:0) after 9m19s
[15:42:55.351] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:17:0)
[15:42:55.379] Build status: STARTED
[15:42:55.379] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:18:0) after 8m48s
[15:51:43.491] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:18:0)
[15:51:43.513] Build status: STARTED
[15:51:43.513] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:19:0) after 3m36s
[15:55:19.729] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:19:0)
[15:55:19.759] Build status: STARTED
[15:55:19.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:20:0) after 7m32s
[16:02:51.878] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:20:0)
[16:02:51.931] Build status: STARTED
[16:02:51.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:21:0) after 1m21s
[16:04:13.053] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:21:0)
[16:04:13.078] Build status: STARTED
[16:04:13.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:22:0) after 8m14s
[16:12:27.097] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:22:0)
[16:12:27.112] Build status: STARTED
[16:12:27.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:23:0) after 4m25s
[16:16:52.095] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:23:0)
[16:16:52.115] Build status: STARTED
[16:16:52.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:24:0) after 4m12s
[16:21:04.141] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:24:0)
[16:21:04.161] Build status: STARTED
[16:21:04.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:25:0) after 2m58s
[16:24:02.184] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:25:0)
[16:24:02.208] Build status: STARTED
[16:24:02.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:26:0) after 9m43s
[16:33:45.293] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:26:0)
[16:33:45.380] Build status: STARTED
[16:33:45.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:27:0) after 3m20s
[16:37:05.397] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:27:0)
[16:37:05.413] Build status: STARTED
[16:37:05.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:28:0) after 5m52s
[16:42:57.438] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:28:0)
[16:42:57.455] Build status: STARTED
[16:42:57.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:29:0) after 8m33s
[16:51:30.824] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:29:0)
[16:51:30.843] Build status: STARTED
[16:51:30.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:30:0) after 7m43s
[16:59:14.071] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:30:0)
[16:59:14.088] Build status: STARTED
[16:59:14.088] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:31:0) after 7m36s
[17:06:50.164] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:31:0)
[17:06:50.195] Build status: STARTED
[17:06:50.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:32:0) after 3m56s
[17:10:46.223] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:32:0)
[17:10:46.258] Build status: STARTED
[17:10:46.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:33:0) after 4m5s
[17:14:51.362] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:33:0)
[17:14:51.391] Build status: STARTED
[17:14:51.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:34:0) after 6m22s
[17:21:13.522] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:34:0)
[17:21:13.544] Build status: STARTED
[17:21:13.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:35:0) after 3m27s
[17:24:40.572] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:35:0)
[17:24:40.592] Build status: STARTED
[17:24:40.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:36:0) after 6m58s
[17:31:38.627] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:36:0)
[17:31:38.653] Build status: STARTED
[17:31:38.653] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:37:0) after 3m46s
[17:35:24.742] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:37:0)
[17:35:24.772] Build status: STARTED
[17:35:24.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:38:0) after 7m30s
[17:42:54.814] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:38:0)
[17:42:54.832] Build status: STARTED
[17:42:54.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:39:0) after 5m56s
[17:48:51.045] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:39:0)
[17:48:51.065] Build status: STARTED
[17:48:51.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:40:0) after 9m2s
[17:57:53.301] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:40:0)
[17:57:53.320] Build status: STARTED
[17:57:53.320] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8888924587241792896:41:0) after 6m18s
[18:02:29.467] Received PubSub notification, asking Buildbucket for the build status
[18:02:29.494] Build:
{
"id": "8723494525372584849",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-08T14:19:04.049558145Z",
"startTime": "2025-02-08T14:19:10.174550335Z",
"endTime": "2025-02-08T18:02:29.205370789Z",
"updateTime": "2025-02-08T18:02:29.205370789Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6ff8a06de9ce125023e117014ce4dca8fcc391d7",
"ref": "refs/heads/main"
}
}
}
[18:02:29.494] Invocation finished in 3h43m26.610618331s with status FAILED