[03:30:22.522] New invocation is queued and will start shortly
[03:30:23.641] Starting the invocation (attempt 1)
[03:30:23.668] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/eb42e94d9793d5426de64266ef637b3267ed0b40"
[03:30:23.668] Popped gitiles commit info from properties and tags
[03:30:23.668] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:30:23.668] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:30:23.669] Buildbucket request:
{
"requestId": "8895397782823846656",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8895397782823846656",
"job": "fuchsia/clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d106a39c3372b924668f203fedbce69aa986cf50"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d106a39c3372b924668f203fedbce69aa986cf50",
"title": "d106a39c3372b924668f203fedbce69aa986cf50",
"url": "https://llvm.googlesource.com/llvm-project/+/d106a39c3372b924668f203fedbce69aa986cf50"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f2d500c61701fc50f5c0c2cd9660a93e15ecc9b9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f2d500c61701fc50f5c0c2cd9660a93e15ecc9b9",
"title": "f2d500c61701fc50f5c0c2cd9660a93e15ecc9b9",
"url": "https://llvm.googlesource.com/llvm-project/+/f2d500c61701fc50f5c0c2cd9660a93e15ecc9b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c2171d748685be8c7ce2366deefe01aaae1c61a0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c2171d748685be8c7ce2366deefe01aaae1c61a0",
"title": "c2171d748685be8c7ce2366deefe01aaae1c61a0",
"url": "https://llvm.googlesource.com/llvm-project/+/c2171d748685be8c7ce2366deefe01aaae1c61a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7c5300e73445310ba40515bc01c2da59d4c58d50"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7c5300e73445310ba40515bc01c2da59d4c58d50",
"title": "7c5300e73445310ba40515bc01c2da59d4c58d50",
"url": "https://llvm.googlesource.com/llvm-project/+/7c5300e73445310ba40515bc01c2da59d4c58d50"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "958ab3a4a53357ddf27b7ccae8bdbae5878d3cca"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@958ab3a4a53357ddf27b7ccae8bdbae5878d3cca",
"title": "958ab3a4a53357ddf27b7ccae8bdbae5878d3cca",
"url": "https://llvm.googlesource.com/llvm-project/+/958ab3a4a53357ddf27b7ccae8bdbae5878d3cca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bfa6c9a959e15e4548e411144fc7eda7d6b7e4dc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bfa6c9a959e15e4548e411144fc7eda7d6b7e4dc",
"title": "bfa6c9a959e15e4548e411144fc7eda7d6b7e4dc",
"url": "https://llvm.googlesource.com/llvm-project/+/bfa6c9a959e15e4548e411144fc7eda7d6b7e4dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "eb42e94d9793d5426de64266ef637b3267ed0b40"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@eb42e94d9793d5426de64266ef637b3267ed0b40",
"title": "eb42e94d9793d5426de64266ef637b3267ed0b40",
"url": "https://llvm.googlesource.com/llvm-project/+/eb42e94d9793d5426de64266ef637b3267ed0b40"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "eb42e94d9793d5426de64266ef637b3267ed0b40",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8895397782823846656"
},
{
"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"
}
}
[03:30:24.081] Scheduled build:
{
"id": "8729967720912871905",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-29T03:30:23.717061045Z",
"updateTime": "2024-11-29T03:30:23.717061045Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "eb42e94d9793d5426de64266ef637b3267ed0b40",
"ref": "refs/heads/main"
}
}
}
[03:30:24.081] Task URL: https://cr-buildbucket.appspot.com/build/8729967720912871905
[03:30:24.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:2:0) after 2m44s
[03:30:25.570] Received PubSub notification, asking Buildbucket for the build status
[03:30:25.596] Build status: SCHEDULED
[03:30:33.901] Received PubSub notification, asking Buildbucket for the build status
[03:30:33.938] Build status: STARTED
[03:33:08.194] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:2:0)
[03:33:08.219] Build status: STARTED
[03:33:08.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:5:0) after 6m10s
[03:39:18.236] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:5:0)
[03:39:18.290] Build status: STARTED
[03:39:18.290] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:6:0) after 7m2s
[03:46:20.304] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:6:0)
[03:46:20.329] Build status: STARTED
[03:46:20.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:7:0) after 1m33s
[03:47:53.445] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:7:0)
[03:47:53.485] Build status: STARTED
[03:47:53.485] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:8:0) after 3m48s
[03:51:41.507] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:8:0)
[03:51:41.529] Build status: STARTED
[03:51:41.529] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:9:0) after 8m15s
[03:59:56.596] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:9:0)
[03:59:56.614] Build status: STARTED
[03:59:56.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:10:0) after 1m16s
[04:01:12.686] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:10:0)
[04:01:12.706] Build status: STARTED
[04:01:12.706] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:11:0) after 3m7s
[04:04:19.822] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:11:0)
[04:04:19.843] Build status: STARTED
[04:04:19.843] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:12:0) after 7m40s
[04:11:59.951] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:12:0)
[04:11:59.988] Build status: STARTED
[04:11:59.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:13:0) after 2m10s
[04:14:10.055] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:13:0)
[04:14:10.073] Build status: STARTED
[04:14:10.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:14:0) after 2m28s
[04:16:38.189] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:14:0)
[04:16:38.233] Build status: STARTED
[04:16:38.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:15:0) after 1m16s
[04:17:54.263] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:15:0)
[04:17:54.293] Build status: STARTED
[04:17:54.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:16:0) after 5m27s
[04:23:21.311] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:16:0)
[04:23:21.386] Build status: STARTED
[04:23:21.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:17:0) after 1m33s
[04:24:54.543] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:17:0)
[04:24:54.568] Build status: STARTED
[04:24:54.568] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:18:0) after 5m51s
[04:30:45.584] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:18:0)
[04:30:45.614] Build status: STARTED
[04:30:45.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:19:0) after 4m51s
[04:35:36.639] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:19:0)
[04:35:36.664] Build status: STARTED
[04:35:36.664] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:20:0) after 3m50s
[04:39:26.741] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:20:0)
[04:39:26.767] Build status: STARTED
[04:39:26.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:21:0) after 6m7s
[04:45:33.978] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:21:0)
[04:45:34.006] Build status: STARTED
[04:45:34.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:22:0) after 6m26s
[04:52:00.312] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:22:0)
[04:52:00.342] Build status: STARTED
[04:52:00.342] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:23:0) after 6m51s
[04:58:51.363] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:23:0)
[04:58:51.444] Build status: STARTED
[04:58:51.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:24:0) after 2m44s
[05:01:35.470] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:24:0)
[05:01:35.501] Build status: STARTED
[05:01:35.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:25:0) after 3m48s
[05:05:23.702] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:25:0)
[05:05:23.760] Build status: STARTED
[05:05:23.760] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:26:0) after 6m44s
[05:12:07.798] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:26:0)
[05:12:07.817] Build status: STARTED
[05:12:07.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:27:0) after 3m44s
[05:15:51.835] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:27:0)
[05:15:51.856] Build status: STARTED
[05:15:51.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:28:0) after 9m1s
[05:24:52.872] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:28:0)
[05:24:52.899] Build status: STARTED
[05:24:52.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:29:0) after 4m59s
[05:29:51.917] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:29:0)
[05:29:51.932] Build status: STARTED
[05:29:51.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:30:0) after 8m53s
[05:38:45.048] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:30:0)
[05:38:45.067] Build status: STARTED
[05:38:45.067] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:31:0) after 8m16s
[05:47:01.280] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:31:0)
[05:47:01.362] Build status: STARTED
[05:47:01.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:32:0) after 1m31s
[05:48:32.385] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:32:0)
[05:48:32.967] Build status: STARTED
[05:48:32.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:33:0) after 6m44s
[05:55:17.086] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:33:0)
[05:55:17.104] Build status: STARTED
[05:55:17.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:34:0) after 4m37s
[05:59:54.187] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:34:0)
[05:59:54.207] Build status: STARTED
[05:59:54.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:35:0) after 7m29s
[06:07:23.746] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:35:0)
[06:07:23.804] Build status: STARTED
[06:07:23.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:36:0) after 1m0s
[06:08:24.146] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:36:0)
[06:08:24.163] Build status: STARTED
[06:08:24.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:37:0) after 7m0s
[06:15:24.233] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:37:0)
[06:15:24.255] Build status: STARTED
[06:15:24.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:38:0) after 7m48s
[06:23:12.291] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:38:0)
[06:23:12.311] Build status: STARTED
[06:23:12.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:39:0) after 8m22s
[06:31:34.416] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:39:0)
[06:31:34.579] Build status: STARTED
[06:31:34.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:40:0) after 9m28s
[06:41:02.602] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:40:0)
[06:41:02.678] Build status: STARTED
[06:41:02.678] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:41:0) after 6m14s
[06:47:16.696] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:41:0)
[06:47:16.719] Build status: STARTED
[06:47:16.719] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:42:0) after 3m32s
[06:50:48.801] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:42:0)
[06:50:48.820] Build status: STARTED
[06:50:48.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:43:0) after 5m57s
[06:56:45.848] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:43:0)
[06:56:45.874] Build status: STARTED
[06:56:45.874] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8895397782823846656:44:0) after 8m32s
[06:59:49.030] Received PubSub notification, asking Buildbucket for the build status
[06:59:49.048] Build:
{
"id": "8729967720912871905",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-11-29T03:30:23.717061045Z",
"startTime": "2024-11-29T03:30:31.466048108Z",
"endTime": "2024-11-29T06:59:48.775656839Z",
"updateTime": "2024-11-29T06:59:48.775656839Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "eb42e94d9793d5426de64266ef637b3267ed0b40",
"ref": "refs/heads/main"
}
}
}
[06:59:49.048] Invocation finished in 3h29m26.537341125s with status FAILED