[05:36:21.342] New invocation is queued and will start shortly
[05:36:22.498] Starting the invocation (attempt 1)
[05:36:22.521] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/906f934b46f675d25ca0b076fc7b7b8066f85f31"
[05:36:22.521] Popped gitiles commit info from properties and tags
[05:36:22.521] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:36:22.521] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:36:22.522] Buildbucket request:
{
"requestId": "8868573154766100336",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868573154766100336",
"job": "fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a36a1ec0546c59917c7b40a8733b1d3f8b5df8f3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a36a1ec0546c59917c7b40a8733b1d3f8b5df8f3",
"title": "a36a1ec0546c59917c7b40a8733b1d3f8b5df8f3",
"url": "https://llvm.googlesource.com/llvm-project/+/a36a1ec0546c59917c7b40a8733b1d3f8b5df8f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b5c658861426e01828b9f92ed767eaa1c83cb812"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b5c658861426e01828b9f92ed767eaa1c83cb812",
"title": "b5c658861426e01828b9f92ed767eaa1c83cb812",
"url": "https://llvm.googlesource.com/llvm-project/+/b5c658861426e01828b9f92ed767eaa1c83cb812"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1cb8afb2587d2fddf8120f98b7646a681da18cff"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1cb8afb2587d2fddf8120f98b7646a681da18cff",
"title": "1cb8afb2587d2fddf8120f98b7646a681da18cff",
"url": "https://llvm.googlesource.com/llvm-project/+/1cb8afb2587d2fddf8120f98b7646a681da18cff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a997e91a2c315c0d6d2956a7a375eda1d3d08131"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a997e91a2c315c0d6d2956a7a375eda1d3d08131",
"title": "a997e91a2c315c0d6d2956a7a375eda1d3d08131",
"url": "https://llvm.googlesource.com/llvm-project/+/a997e91a2c315c0d6d2956a7a375eda1d3d08131"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e8e0108a873c1efd7f614251f22de67b16a05c8c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e8e0108a873c1efd7f614251f22de67b16a05c8c",
"title": "e8e0108a873c1efd7f614251f22de67b16a05c8c",
"url": "https://llvm.googlesource.com/llvm-project/+/e8e0108a873c1efd7f614251f22de67b16a05c8c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7d748a9ceb3716a216a8b586b1d31e046bdee039"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7d748a9ceb3716a216a8b586b1d31e046bdee039",
"title": "7d748a9ceb3716a216a8b586b1d31e046bdee039",
"url": "https://llvm.googlesource.com/llvm-project/+/7d748a9ceb3716a216a8b586b1d31e046bdee039"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "22e17a6383ebdc7f2b4563f2716ded478a2dc1f5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@22e17a6383ebdc7f2b4563f2716ded478a2dc1f5",
"title": "22e17a6383ebdc7f2b4563f2716ded478a2dc1f5",
"url": "https://llvm.googlesource.com/llvm-project/+/22e17a6383ebdc7f2b4563f2716ded478a2dc1f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "906f934b46f675d25ca0b076fc7b7b8066f85f31"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@906f934b46f675d25ca0b076fc7b7b8066f85f31",
"title": "906f934b46f675d25ca0b076fc7b7b8066f85f31",
"url": "https://llvm.googlesource.com/llvm-project/+/906f934b46f675d25ca0b076fc7b7b8066f85f31"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "906f934b46f675d25ca0b076fc7b7b8066f85f31",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868573154766100336"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[05:36:23.565] Scheduled build:
{
"id": "8703143092502468273",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-21T05:36:22.879987318Z",
"updateTime": "2025-09-21T05:36:22.879987318Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "906f934b46f675d25ca0b076fc7b7b8066f85f31",
"ref": "refs/heads/main"
}
}
}
[05:36:23.565] Task URL: https://cr-buildbucket.appspot.com/build/8703143092502468273
[05:36:23.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:2:0) after 9m50s
[05:36:24.574] Received PubSub notification, asking Buildbucket for the build status
[05:36:24.591] Build status: SCHEDULED
[05:36:29.731] Received PubSub notification, asking Buildbucket for the build status
[05:36:29.748] Build status: STARTED
[05:46:13.583] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:2:0)
[05:46:13.617] Build status: STARTED
[05:46:13.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:5:0) after 7m46s
[05:53:59.634] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:5:0)
[05:53:59.650] Build status: STARTED
[05:53:59.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:6:0) after 9m50s
[06:03:49.663] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:6:0)
[06:03:49.685] Build status: STARTED
[06:03:49.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:7:0) after 4m33s
[06:08:22.736] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:7:0)
[06:08:22.758] Build status: STARTED
[06:08:22.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:8:0) after 7m6s
[06:15:28.773] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:8:0)
[06:15:28.792] Build status: STARTED
[06:15:28.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:9:0) after 9m33s
[06:25:01.870] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:9:0)
[06:25:01.891] Build status: STARTED
[06:25:01.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:10:0) after 3m54s
[06:28:55.902] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:10:0)
[06:28:55.919] Build status: STARTED
[06:28:55.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:11:0) after 3m20s
[06:32:16.455] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:11:0)
[06:32:16.474] Build status: STARTED
[06:32:16.474] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:12:0) after 3m25s
[06:35:41.496] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:12:0)
[06:35:41.516] Build status: STARTED
[06:35:41.516] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:13:0) after 6m25s
[06:42:06.812] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:13:0)
[06:42:06.827] Build status: STARTED
[06:42:06.827] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:14:0) after 8m6s
[06:50:12.942] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:14:0)
[06:50:12.961] Build status: STARTED
[06:50:12.961] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:15:0) after 4m28s
[06:54:40.991] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:15:0)
[06:54:41.007] Build status: STARTED
[06:54:41.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:16:0) after 1m30s
[06:56:11.136] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:16:0)
[06:56:11.153] Build status: STARTED
[06:56:11.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:17:0) after 4m54s
[07:01:05.169] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:17:0)
[07:01:05.209] Build status: STARTED
[07:01:05.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:18:0) after 5m27s
[07:06:32.306] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:18:0)
[07:06:32.331] Build status: STARTED
[07:06:32.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:19:0) after 9m54s
[07:16:26.375] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:19:0)
[07:16:26.392] Build status: STARTED
[07:16:26.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:20:0) after 2m13s
[07:18:39.597] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:20:0)
[07:18:39.618] Build status: STARTED
[07:18:39.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:21:0) after 1m46s
[07:20:25.650] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:21:0)
[07:20:25.673] Build status: STARTED
[07:20:25.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:22:0) after 9m29s
[07:29:54.689] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:22:0)
[07:29:54.774] Build status: STARTED
[07:29:54.774] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:23:0) after 3m59s
[07:33:53.955] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:23:0)
[07:33:53.985] Build status: STARTED
[07:33:53.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:24:0) after 4m16s
[07:38:10.002] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:24:0)
[07:38:10.046] Build status: STARTED
[07:38:10.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:25:0) after 4m35s
[07:42:45.061] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:25:0)
[07:42:45.078] Build status: STARTED
[07:42:45.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:26:0) after 8m12s
[07:50:57.193] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:26:0)
[07:50:57.231] Build status: STARTED
[07:50:57.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:27:0) after 4m36s
[07:55:33.253] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:27:0)
[07:55:33.277] Build status: STARTED
[07:55:33.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:28:0) after 3m52s
[07:59:25.395] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:28:0)
[07:59:25.416] Build status: STARTED
[07:59:25.416] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:29:0) after 7m14s
[08:06:39.507] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:29:0)
[08:06:39.537] Build status: STARTED
[08:06:39.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:30:0) after 2m50s
[08:09:29.714] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:30:0)
[08:09:29.732] Build status: STARTED
[08:09:29.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:31:0) after 9m57s
[08:19:26.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:31:0)
[08:19:26.862] Build status: STARTED
[08:19:26.862] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:32:0) after 7m16s
[08:26:42.888] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:32:0)
[08:26:42.977] Build status: STARTED
[08:26:42.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:33:0) after 1m15s
[08:27:58.166] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:33:0)
[08:27:58.251] Build status: STARTED
[08:27:58.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:34:0) after 5m43s
[08:33:41.273] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:34:0)
[08:33:41.294] Build status: STARTED
[08:33:41.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:35:0) after 5m58s
[08:39:39.308] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:35:0)
[08:39:39.554] Build status: STARTED
[08:39:39.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:36:0) after 7m12s
[08:46:51.610] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:36:0)
[08:46:51.738] Build status: STARTED
[08:46:51.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8868573154766100336:37:0) after 7m0s
[08:53:38.789] Received PubSub notification, asking Buildbucket for the build status
[08:53:38.804] Build:
{
"id": "8703143092502468273",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-21T05:36:22.879987318Z",
"startTime": "2025-09-21T05:36:29.509578189Z",
"endTime": "2025-09-21T08:53:18.245355455Z",
"updateTime": "2025-09-21T08:53:18.245355455Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "906f934b46f675d25ca0b076fc7b7b8066f85f31",
"ref": "refs/heads/main"
}
}
}
[08:53:38.804] Invocation finished in 3h17m17.466708514s with status SUCCEEDED