[03:53:33.247] New invocation is queued and will start shortly
[03:53:34.391] Starting the invocation (attempt 1)
[03:53:34.419] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/b2b0e6c01e819e078fb25a7f882de58a72cfc130"
[03:53:34.419] Popped gitiles commit info from properties and tags
[03:53:34.419] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:53:34.419] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:53:34.420] Buildbucket request:
{
"requestId": "8899382591059807152",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899382591059807152",
"job": "fuchsia/clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ed0fd13783a68af6033b2c489eb830af0726856c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ed0fd13783a68af6033b2c489eb830af0726856c",
"title": "ed0fd13783a68af6033b2c489eb830af0726856c",
"url": "https://llvm.googlesource.com/llvm-project/+/ed0fd13783a68af6033b2c489eb830af0726856c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "eca3206d29e7ce97dd6336deaa3da96be37f8277"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@eca3206d29e7ce97dd6336deaa3da96be37f8277",
"title": "eca3206d29e7ce97dd6336deaa3da96be37f8277",
"url": "https://llvm.googlesource.com/llvm-project/+/eca3206d29e7ce97dd6336deaa3da96be37f8277"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "69f7758ddba662b63667507f2c472c008909dd7e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@69f7758ddba662b63667507f2c472c008909dd7e",
"title": "69f7758ddba662b63667507f2c472c008909dd7e",
"url": "https://llvm.googlesource.com/llvm-project/+/69f7758ddba662b63667507f2c472c008909dd7e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "210140ab6ac8126e5beec65209602cd55c4d09c7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@210140ab6ac8126e5beec65209602cd55c4d09c7",
"title": "210140ab6ac8126e5beec65209602cd55c4d09c7",
"url": "https://llvm.googlesource.com/llvm-project/+/210140ab6ac8126e5beec65209602cd55c4d09c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6d13cc9411b998aabf1a55e0813236ba7a278929"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6d13cc9411b998aabf1a55e0813236ba7a278929",
"title": "6d13cc9411b998aabf1a55e0813236ba7a278929",
"url": "https://llvm.googlesource.com/llvm-project/+/6d13cc9411b998aabf1a55e0813236ba7a278929"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bb89988174e5f1a9a419637cadae07e4e8c61c3e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bb89988174e5f1a9a419637cadae07e4e8c61c3e",
"title": "bb89988174e5f1a9a419637cadae07e4e8c61c3e",
"url": "https://llvm.googlesource.com/llvm-project/+/bb89988174e5f1a9a419637cadae07e4e8c61c3e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4cc6a08142129d351840c3c63d9372a2b66930b2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4cc6a08142129d351840c3c63d9372a2b66930b2",
"title": "4cc6a08142129d351840c3c63d9372a2b66930b2",
"url": "https://llvm.googlesource.com/llvm-project/+/4cc6a08142129d351840c3c63d9372a2b66930b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b2b0e6c01e819e078fb25a7f882de58a72cfc130"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b2b0e6c01e819e078fb25a7f882de58a72cfc130",
"title": "b2b0e6c01e819e078fb25a7f882de58a72cfc130",
"url": "https://llvm.googlesource.com/llvm-project/+/b2b0e6c01e819e078fb25a7f882de58a72cfc130"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "b2b0e6c01e819e078fb25a7f882de58a72cfc130",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899382591059807152"
},
{
"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:53:34.870] Scheduled build:
{
"id": "8733952529117154289",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-16T03:53:34.477670151Z",
"updateTime": "2024-10-16T03:53:34.477670151Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "b2b0e6c01e819e078fb25a7f882de58a72cfc130",
"ref": "refs/heads/main"
}
}
}
[03:53:34.870] Task URL: https://cr-buildbucket.appspot.com/build/8733952529117154289
[03:53:34.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:2:0) after 9m14s
[03:53:35.791] Received PubSub notification, asking Buildbucket for the build status
[03:53:35.811] Build status: SCHEDULED
[03:53:40.016] Received PubSub notification, asking Buildbucket for the build status
[03:53:40.038] Build status: STARTED
[04:02:48.978] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:2:0)
[04:02:49.069] Build status: STARTED
[04:02:49.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:5:0) after 5m11s
[04:08:00.102] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:5:0)
[04:08:00.137] Build status: STARTED
[04:08:00.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:6:0) after 7m36s
[04:15:36.218] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:6:0)
[04:15:36.378] Build status: STARTED
[04:15:36.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:7:0) after 7m57s
[04:23:33.399] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:7:0)
[04:23:33.420] Build status: STARTED
[04:23:33.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:8:0) after 4m4s
[04:27:37.435] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:8:0)
[04:27:37.454] Build status: STARTED
[04:27:37.454] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:9:0) after 6m16s
[04:33:53.472] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:9:0)
[04:33:53.493] Build status: STARTED
[04:33:53.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:10:0) after 6m17s
[04:40:10.516] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:10:0)
[04:40:10.538] Build status: STARTED
[04:40:10.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:11:0) after 6m12s
[04:46:22.554] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:11:0)
[04:46:22.592] Build status: STARTED
[04:46:22.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:12:0) after 1m55s
[04:48:17.590] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:12:0)
[04:48:17.615] Build status: STARTED
[04:48:17.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:13:0) after 7m29s
[04:55:46.757] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:13:0)
[04:55:46.778] Build status: STARTED
[04:55:46.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:14:0) after 5m58s
[05:01:44.798] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:14:0)
[05:01:44.830] Build status: STARTED
[05:01:44.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:15:0) after 2m25s
[05:04:10.310] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:15:0)
[05:04:10.412] Build status: STARTED
[05:04:10.412] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:16:0) after 2m46s
[05:06:56.646] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:16:0)
[05:06:56.669] Build status: STARTED
[05:06:56.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:17:0) after 3m2s
[05:09:58.689] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:17:0)
[05:09:58.716] Build status: STARTED
[05:09:58.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:18:0) after 5m6s
[05:15:04.736] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:18:0)
[05:15:04.758] Build status: STARTED
[05:15:04.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:19:0) after 3m51s
[05:18:55.888] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:19:0)
[05:18:55.908] Build status: STARTED
[05:18:55.908] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:20:0) after 4m36s
[05:23:32.027] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:20:0)
[05:23:32.054] Build status: STARTED
[05:23:32.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:21:0) after 9m38s
[05:33:10.152] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:21:0)
[05:33:10.170] Build status: STARTED
[05:33:10.170] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:22:0) after 3m9s
[05:36:19.270] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:22:0)
[05:36:19.288] Build status: STARTED
[05:36:19.288] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:23:0) after 6m42s
[05:43:01.481] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:23:0)
[05:43:01.500] Build status: STARTED
[05:43:01.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:24:0) after 2m22s
[05:45:23.519] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:24:0)
[05:45:23.536] Build status: STARTED
[05:45:23.536] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:25:0) after 4m9s
[05:49:32.754] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:25:0)
[05:49:32.770] Build status: STARTED
[05:49:32.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:26:0) after 1m52s
[05:51:24.786] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:26:0)
[05:51:24.808] Build status: STARTED
[05:51:24.808] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:27:0) after 3m29s
[05:54:54.350] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:27:0)
[05:54:54.374] Build status: STARTED
[05:54:54.374] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:28:0) after 9m23s
[06:04:18.120] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:28:0)
[06:04:18.194] Build status: STARTED
[06:04:18.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:29:0) after 2m27s
[06:06:45.236] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:29:0)
[06:06:45.258] Build status: STARTED
[06:06:45.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:30:0) after 5m57s
[06:12:42.497] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:30:0)
[06:12:42.517] Build status: STARTED
[06:12:42.517] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:31:0) after 9m34s
[06:22:16.649] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:31:0)
[06:22:16.672] Build status: STARTED
[06:22:16.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:32:0) after 6m21s
[06:28:37.692] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:32:0)
[06:28:37.723] Build status: STARTED
[06:28:37.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:33:0) after 8m28s
[06:37:06.029] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:33:0)
[06:37:06.056] Build status: STARTED
[06:37:06.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:34:0) after 9m44s
[06:46:50.246] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:34:0)
[06:46:50.274] Build status: STARTED
[06:46:50.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:35:0) after 8m15s
[06:55:05.479] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:35:0)
[06:55:05.496] Build status: STARTED
[06:55:05.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:36:0) after 9m58s
[07:05:03.668] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:36:0)
[07:05:03.688] Build status: STARTED
[07:05:03.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:37:0) after 4m1s
[07:09:04.703] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:37:0)
[07:09:04.752] Build status: STARTED
[07:09:04.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:38:0) after 7m32s
[07:16:36.772] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:38:0)
[07:16:36.793] Build status: STARTED
[07:16:36.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:39:0) after 4m46s
[07:21:22.838] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:39:0)
[07:21:22.853] Build status: STARTED
[07:21:22.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:40:0) after 3m5s
[07:24:27.871] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:40:0)
[07:24:27.891] Build status: STARTED
[07:24:27.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899382591059807152:41:0) after 9m55s
[07:26:37.283] Received PubSub notification, asking Buildbucket for the build status
[07:26:37.299] Build:
{
"id": "8733952529117154289",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-16T03:53:34.477670151Z",
"startTime": "2024-10-16T03:53:39.786751585Z",
"endTime": "2024-10-16T07:26:36.922420486Z",
"updateTime": "2024-10-16T07:26:36.922420486Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "b2b0e6c01e819e078fb25a7f882de58a72cfc130",
"ref": "refs/heads/main"
}
}
}
[07:26:37.299] Invocation finished in 3h33m4.057303147s with status SUCCEEDED