[14:08:18.985] New invocation is queued and will start shortly
[14:08:20.095] Starting the invocation (attempt 1)
[14:08:20.165] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/c2a8739cd1fbdafa2e16b006d35ad94cc6995163"
[14:08:20.165] Popped gitiles commit info from properties and tags
[14:08:20.165] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:08:20.165] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:08:20.166] Buildbucket request:
{
"requestId": "8859934233175680896",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8859934233175680896",
"job": "fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0d1c396ce8178baf05f277b16bf41b8a6b847d6d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0d1c396ce8178baf05f277b16bf41b8a6b847d6d",
"title": "0d1c396ce8178baf05f277b16bf41b8a6b847d6d",
"url": "https://llvm.googlesource.com/llvm-project/+/0d1c396ce8178baf05f277b16bf41b8a6b847d6d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "64e7f72b365802b1cf3d868e2f15d50a2f2bb634"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@64e7f72b365802b1cf3d868e2f15d50a2f2bb634",
"title": "64e7f72b365802b1cf3d868e2f15d50a2f2bb634",
"url": "https://llvm.googlesource.com/llvm-project/+/64e7f72b365802b1cf3d868e2f15d50a2f2bb634"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6d1e7d4982fabc9e245897056a5425496df6a7a3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6d1e7d4982fabc9e245897056a5425496df6a7a3",
"title": "6d1e7d4982fabc9e245897056a5425496df6a7a3",
"url": "https://llvm.googlesource.com/llvm-project/+/6d1e7d4982fabc9e245897056a5425496df6a7a3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "71bba125879e6f2133719bf03149542d70fd3930"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@71bba125879e6f2133719bf03149542d70fd3930",
"title": "71bba125879e6f2133719bf03149542d70fd3930",
"url": "https://llvm.googlesource.com/llvm-project/+/71bba125879e6f2133719bf03149542d70fd3930"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "11a1fc412b37b8907064db447bebf5f0d42ff6a4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@11a1fc412b37b8907064db447bebf5f0d42ff6a4",
"title": "11a1fc412b37b8907064db447bebf5f0d42ff6a4",
"url": "https://llvm.googlesource.com/llvm-project/+/11a1fc412b37b8907064db447bebf5f0d42ff6a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "991455e69e93c0ce88e927eddd28a9ab34d1f8b2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@991455e69e93c0ce88e927eddd28a9ab34d1f8b2",
"title": "991455e69e93c0ce88e927eddd28a9ab34d1f8b2",
"url": "https://llvm.googlesource.com/llvm-project/+/991455e69e93c0ce88e927eddd28a9ab34d1f8b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c2a8739cd1fbdafa2e16b006d35ad94cc6995163"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c2a8739cd1fbdafa2e16b006d35ad94cc6995163",
"title": "c2a8739cd1fbdafa2e16b006d35ad94cc6995163",
"url": "https://llvm.googlesource.com/llvm-project/+/c2a8739cd1fbdafa2e16b006d35ad94cc6995163"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c2a8739cd1fbdafa2e16b006d35ad94cc6995163",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8859934233175680896"
},
{
"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"
}
}
[14:08:20.557] Scheduled build:
{
"id": "8694504171228585873",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-12-25T14:08:20.220943920Z",
"updateTime": "2025-12-25T14:08:20.220943920Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c2a8739cd1fbdafa2e16b006d35ad94cc6995163",
"ref": "refs/heads/main"
}
},
"canary": true
}
[14:08:20.557] Task URL: https://cr-buildbucket.appspot.com/build/8694504171228585873
[14:08:20.558] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:2:0) after 2m46s
[14:08:21.478] Received PubSub notification, asking Buildbucket for the build status
[14:08:21.499] Build status: SCHEDULED
[14:08:24.578] Received PubSub notification, asking Buildbucket for the build status
[14:08:24.646] Build status: STARTED
[14:11:06.794] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:2:0)
[14:11:06.816] Build status: STARTED
[14:11:06.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:5:0) after 1m50s
[14:12:56.933] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:5:0)
[14:12:56.955] Build status: STARTED
[14:12:56.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:6:0) after 5m17s
[14:18:13.936] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:6:0)
[14:18:13.993] Build status: STARTED
[14:18:13.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:7:0) after 2m32s
[14:20:46.156] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:7:0)
[14:20:46.177] Build status: STARTED
[14:20:46.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:8:0) after 7m17s
[14:28:03.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:8:0)
[14:28:03.360] Build status: STARTED
[14:28:03.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:9:0) after 5m13s
[14:33:16.399] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:9:0)
[14:33:16.418] Build status: STARTED
[14:33:16.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:10:0) after 5m16s
[14:38:32.579] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:10:0)
[14:38:32.603] Build status: STARTED
[14:38:32.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:11:0) after 6m42s
[14:45:14.672] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:11:0)
[14:45:14.692] Build status: STARTED
[14:45:14.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:12:0) after 1m28s
[14:46:42.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:12:0)
[14:46:42.727] Build status: STARTED
[14:46:42.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:13:0) after 2m22s
[14:49:05.260] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:13:0)
[14:49:05.272] Build status: STARTED
[14:49:05.272] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:14:0) after 7m31s
[14:56:36.359] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:14:0)
[14:56:36.377] Build status: STARTED
[14:56:36.377] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:15:0) after 7m44s
[15:04:20.606] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:15:0)
[15:04:20.620] Build status: STARTED
[15:04:20.620] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:16:0) after 9m16s
[15:13:36.772] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:16:0)
[15:13:36.795] Build status: STARTED
[15:13:36.795] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:17:0) after 9m27s
[15:23:03.998] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:17:0)
[15:23:04.174] Build status: STARTED
[15:23:04.175] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:18:0) after 3m3s
[15:26:07.538] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:18:0)
[15:26:07.553] Build status: STARTED
[15:26:07.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:19:0) after 4m5s
[15:30:12.580] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:19:0)
[15:30:12.597] Build status: STARTED
[15:30:12.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:20:0) after 7m5s
[15:37:17.811] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:20:0)
[15:37:17.824] Build status: STARTED
[15:37:17.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:21:0) after 3m32s
[15:40:49.844] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:21:0)
[15:40:49.857] Build status: STARTED
[15:40:49.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:22:0) after 9m23s
[15:50:13.119] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:22:0)
[15:50:13.138] Build status: STARTED
[15:50:13.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:23:0) after 2m36s
[15:52:49.235] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:23:0)
[15:52:49.250] Build status: STARTED
[15:52:49.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:24:0) after 1m51s
[15:54:40.629] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:24:0)
[15:54:40.723] Build status: STARTED
[15:54:40.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:25:0) after 7m33s
[16:02:13.737] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:25:0)
[16:02:13.752] Build status: STARTED
[16:02:13.753] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:26:0) after 9m32s
[16:11:45.768] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:26:0)
[16:11:45.782] Build status: STARTED
[16:11:45.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:27:0) after 4m53s
[16:16:38.796] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:27:0)
[16:16:38.820] Build status: STARTED
[16:16:38.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:28:0) after 1m0s
[16:17:38.971] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:28:0)
[16:17:39.058] Build status: STARTED
[16:17:39.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:29:0) after 6m36s
[16:24:15.070] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:29:0)
[16:24:15.082] Build status: STARTED
[16:24:15.082] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:30:0) after 4m8s
[16:28:23.076] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:30:0)
[16:28:23.099] Build status: STARTED
[16:28:23.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:31:0) after 9m30s
[16:37:53.114] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:31:0)
[16:37:53.127] Build status: STARTED
[16:37:53.127] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:32:0) after 3m22s
[16:41:15.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:32:0)
[16:41:15.183] Build status: STARTED
[16:41:15.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:33:0) after 2m9s
[16:43:24.362] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:33:0)
[16:43:24.378] Build status: STARTED
[16:43:24.378] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:34:0) after 3m43s
[16:47:07.397] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:34:0)
[16:47:07.415] Build status: STARTED
[16:47:07.415] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:35:0) after 8m3s
[16:55:10.435] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:35:0)
[16:55:10.487] Build status: STARTED
[16:55:10.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:36:0) after 7m37s
[17:02:47.567] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:36:0)
[17:02:47.579] Build status: STARTED
[17:02:47.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:37:0) after 8m22s
[17:11:09.591] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:37:0)
[17:11:09.606] Build status: STARTED
[17:11:09.606] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:38:0) after 3m16s
[17:14:25.802] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:38:0)
[17:14:25.819] Build status: STARTED
[17:14:25.819] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:39:0) after 6m34s
[17:20:59.898] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:39:0)
[17:20:59.915] Build status: STARTED
[17:20:59.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-prod-unbounded-trigger-40be1bb5:8859934233175680896:40:0) after 4m2s
[17:21:23.051] Received PubSub notification, asking Buildbucket for the build status
[17:21:23.066] Build:
{
"id": "8694504171228585873",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-12-25T14:08:20.220943920Z",
"startTime": "2025-12-25T14:08:24.349445119Z",
"endTime": "2025-12-25T17:21:22.800021128Z",
"updateTime": "2025-12-25T17:21:22.800021128Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c2a8739cd1fbdafa2e16b006d35ad94cc6995163",
"ref": "refs/heads/main"
}
},
"canary": true
}
[17:21:23.066] Invocation finished in 3h13m4.086679479s with status SUCCEEDED