[22:30:50.995] New invocation is queued and will start shortly
[22:30:52.073] Starting the invocation (attempt 1)
[22:30:52.099] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09"
[22:30:52.099] Popped gitiles commit info from properties and tags
[22:30:52.099] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:30:52.099] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:30:52.099] Buildbucket request:
{
"requestId": "8898315730254827136",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898315730254827136",
"job": "fuchsia/clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "eef3766ae5a39fea6f7f81ac444f878969743d85"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@eef3766ae5a39fea6f7f81ac444f878969743d85",
"title": "eef3766ae5a39fea6f7f81ac444f878969743d85",
"url": "https://llvm.googlesource.com/llvm-project/+/eef3766ae5a39fea6f7f81ac444f878969743d85"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5621929f7f4878c318deecb592cd03f5ecbb83ba"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5621929f7f4878c318deecb592cd03f5ecbb83ba",
"title": "5621929f7f4878c318deecb592cd03f5ecbb83ba",
"url": "https://llvm.googlesource.com/llvm-project/+/5621929f7f4878c318deecb592cd03f5ecbb83ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09",
"title": "7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09",
"url": "https://llvm.googlesource.com/llvm-project/+/7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898315730254827136"
},
{
"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"
}
}
[22:30:52.715] Scheduled build:
{
"id": "8732885668335638897",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-27T22:30:52.204001680Z",
"updateTime": "2024-10-27T22:30:52.204001680Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09",
"ref": "refs/heads/main"
}
}
}
[22:30:52.715] Task URL: https://cr-buildbucket.appspot.com/build/8732885668335638897
[22:30:52.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:2:0) after 8m52s
[22:30:56.550] Received PubSub notification, asking Buildbucket for the build status
[22:30:56.577] Build status: SCHEDULED
[22:31:06.024] Received PubSub notification, asking Buildbucket for the build status
[22:31:06.045] Build status: STARTED
[22:39:44.920] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:2:0)
[22:39:44.941] Build status: STARTED
[22:39:44.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:5:0) after 3m3s
[22:42:47.955] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:5:0)
[22:42:47.972] Build status: STARTED
[22:42:47.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:6:0) after 3m35s
[22:46:23.232] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:6:0)
[22:46:23.247] Build status: STARTED
[22:46:23.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:7:0) after 7m34s
[22:53:57.391] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:7:0)
[22:53:57.534] Build status: STARTED
[22:53:57.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:8:0) after 2m34s
[22:56:31.919] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:8:0)
[22:56:31.945] Build status: STARTED
[22:56:31.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:9:0) after 5m7s
[23:01:39.004] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:9:0)
[23:01:39.091] Build status: STARTED
[23:01:39.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:10:0) after 5m31s
[23:07:10.205] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:10:0)
[23:07:10.223] Build status: STARTED
[23:07:10.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:11:0) after 4m20s
[23:11:30.240] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:11:0)
[23:11:30.272] Build status: STARTED
[23:11:30.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:12:0) after 1m43s
[23:13:13.306] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:12:0)
[23:13:13.335] Build status: STARTED
[23:13:13.335] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:13:0) after 5m22s
[23:18:35.369] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:13:0)
[23:18:35.447] Build status: STARTED
[23:18:35.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:14:0) after 8m19s
[23:26:54.501] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:14:0)
[23:26:54.529] Build status: STARTED
[23:26:54.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:15:0) after 8m6s
[23:35:00.706] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:15:0)
[23:35:00.723] Build status: STARTED
[23:35:00.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:16:0) after 3m10s
[23:38:10.886] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:16:0)
[23:38:10.902] Build status: STARTED
[23:38:10.902] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:17:0) after 1m49s
[23:40:00.453] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:17:0)
[23:40:00.522] Build status: STARTED
[23:40:00.522] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:18:0) after 7m59s
[23:47:59.537] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:18:0)
[23:47:59.553] Build status: STARTED
[23:47:59.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:19:0) after 6m9s
[23:54:08.566] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:19:0)
[23:54:08.588] Build status: STARTED
[23:54:08.588] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:20:0) after 8m3s
[00:02:11.605] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:20:0)
[00:02:11.621] Build status: STARTED
[00:02:11.621] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:21:0) after 9m23s
[00:11:34.674] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:21:0)
[00:11:34.690] Build status: STARTED
[00:11:34.690] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:22:0) after 4m25s
[00:15:59.708] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:22:0)
[00:15:59.728] Build status: STARTED
[00:15:59.728] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:23:0) after 5m36s
[00:21:35.787] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:23:0)
[00:21:35.818] Build status: STARTED
[00:21:35.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:24:0) after 4m56s
[00:26:31.972] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:24:0)
[00:26:31.989] Build status: STARTED
[00:26:31.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:25:0) after 3m30s
[00:30:02.446] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:25:0)
[00:30:02.466] Build status: STARTED
[00:30:02.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:26:0) after 8m51s
[00:38:53.483] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:26:0)
[00:38:53.563] Build status: STARTED
[00:38:53.563] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:27:0) after 3m5s
[00:41:58.812] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:27:0)
[00:41:58.871] Build status: STARTED
[00:41:58.871] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:28:0) after 5m25s
[00:47:23.886] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:28:0)
[00:47:23.910] Build status: STARTED
[00:47:23.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:29:0) after 8m47s
[00:56:10.915] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:29:0)
[00:56:10.935] Build status: STARTED
[00:56:10.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:30:0) after 6m12s
[01:02:22.958] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:30:0)
[01:02:23.043] Build status: STARTED
[01:02:23.043] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:31:0) after 7m32s
[01:09:55.257] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:31:0)
[01:09:55.278] Build status: STARTED
[01:09:55.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:32:0) after 2m30s
[01:12:25.334] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:32:0)
[01:12:25.354] Build status: STARTED
[01:12:25.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:33:0) after 3m41s
[01:16:06.488] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:33:0)
[01:16:06.506] Build status: STARTED
[01:16:06.507] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:34:0) after 2m16s
[01:18:22.669] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:34:0)
[01:18:22.686] Build status: STARTED
[01:18:22.686] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:35:0) after 6m40s
[01:25:02.821] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:35:0)
[01:25:02.838] Build status: STARTED
[01:25:02.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:36:0) after 5m24s
[01:30:26.860] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:36:0)
[01:30:26.905] Build status: STARTED
[01:30:26.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:37:0) after 1m47s
[01:32:14.260] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:37:0)
[01:32:14.280] Build status: STARTED
[01:32:14.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:38:0) after 6m55s
[01:39:09.299] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:38:0)
[01:39:09.321] Build status: STARTED
[01:39:09.321] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:39:0) after 4m19s
[01:43:28.514] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:39:0)
[01:43:28.532] Build status: STARTED
[01:43:28.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:40:0) after 1m31s
[01:44:59.699] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:40:0)
[01:44:59.716] Build status: STARTED
[01:44:59.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:41:0) after 7m35s
[01:52:34.779] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:41:0)
[01:52:34.802] Build status: STARTED
[01:52:34.802] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:42:0) after 3m22s
[01:55:56.831] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:42:0)
[01:55:56.848] Build status: STARTED
[01:55:56.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:43:0) after 8m54s
[02:04:50.873] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:43:0)
[02:04:50.889] Build status: STARTED
[02:04:50.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8898315730254827136:44:0) after 7m41s
[02:11:14.658] Received PubSub notification, asking Buildbucket for the build status
[02:11:14.680] Build:
{
"id": "8732885668335638897",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-27T22:30:52.204001680Z",
"startTime": "2024-10-27T22:31:04.179000857Z",
"endTime": "2024-10-28T02:10:59.727749814Z",
"updateTime": "2024-10-28T02:10:59.727749814Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "7b3da7b3b2b0e2f322dddf1f343571cc7fd09b09",
"ref": "refs/heads/main"
}
}
}
[02:11:14.680] Invocation finished in 3h40m23.689603477s with status FAILED