[16:06:11.064] New invocation is queued and will start shortly
[16:06:12.117] Starting the invocation (attempt 1)
[16:06:12.149] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/15980624ac516ae2dbbf1f2cd24d63de0f9fd2be"
[16:06:12.149] Popped gitiles commit info from properties and tags
[16:06:12.149] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:06:12.149] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:06:12.150] Buildbucket request:
{
"requestId": "8873425765563936576",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873425765563936576",
"job": "fuchsia/prod-clang-ci-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "73245b06b3da19ef70e04cf0f0a0d0df1ba82a57"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@73245b06b3da19ef70e04cf0f0a0d0df1ba82a57",
"title": "73245b06b3da19ef70e04cf0f0a0d0df1ba82a57",
"url": "https://llvm.googlesource.com/llvm-project/+/73245b06b3da19ef70e04cf0f0a0d0df1ba82a57"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8e7b02fc0cd5f63a14f4117866f860b7f174baf3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8e7b02fc0cd5f63a14f4117866f860b7f174baf3",
"title": "8e7b02fc0cd5f63a14f4117866f860b7f174baf3",
"url": "https://llvm.googlesource.com/llvm-project/+/8e7b02fc0cd5f63a14f4117866f860b7f174baf3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e68a20e0b7623738d6af736d3aa02625cba6126a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e68a20e0b7623738d6af736d3aa02625cba6126a",
"title": "e68a20e0b7623738d6af736d3aa02625cba6126a",
"url": "https://llvm.googlesource.com/llvm-project/+/e68a20e0b7623738d6af736d3aa02625cba6126a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "30ad2e24ab3392b1b1f022422255f010ed6dbd63"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@30ad2e24ab3392b1b1f022422255f010ed6dbd63",
"title": "30ad2e24ab3392b1b1f022422255f010ed6dbd63",
"url": "https://llvm.googlesource.com/llvm-project/+/30ad2e24ab3392b1b1f022422255f010ed6dbd63"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d4562a19911e05cf9d81c6857e94cfb1307d4315"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d4562a19911e05cf9d81c6857e94cfb1307d4315",
"title": "d4562a19911e05cf9d81c6857e94cfb1307d4315",
"url": "https://llvm.googlesource.com/llvm-project/+/d4562a19911e05cf9d81c6857e94cfb1307d4315"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "84576c7b5d4f838d9154640bbaf36b703bc552f5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@84576c7b5d4f838d9154640bbaf36b703bc552f5",
"title": "84576c7b5d4f838d9154640bbaf36b703bc552f5",
"url": "https://llvm.googlesource.com/llvm-project/+/84576c7b5d4f838d9154640bbaf36b703bc552f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5e150bb78185b424a8e6e01aa2c4907dde409777"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5e150bb78185b424a8e6e01aa2c4907dde409777",
"title": "5e150bb78185b424a8e6e01aa2c4907dde409777",
"url": "https://llvm.googlesource.com/llvm-project/+/5e150bb78185b424a8e6e01aa2c4907dde409777"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e874615a62e6a5cb7be0be67b0c4c66c4719f67a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e874615a62e6a5cb7be0be67b0c4c66c4719f67a",
"title": "e874615a62e6a5cb7be0be67b0c4c66c4719f67a",
"url": "https://llvm.googlesource.com/llvm-project/+/e874615a62e6a5cb7be0be67b0c4c66c4719f67a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "99fda1a09cf7ce97fbd0ea74101e4fc3283f3428"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@99fda1a09cf7ce97fbd0ea74101e4fc3283f3428",
"title": "99fda1a09cf7ce97fbd0ea74101e4fc3283f3428",
"url": "https://llvm.googlesource.com/llvm-project/+/99fda1a09cf7ce97fbd0ea74101e4fc3283f3428"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1a974527bb986d73afdb62b57ead54e33b54f8ca"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1a974527bb986d73afdb62b57ead54e33b54f8ca",
"title": "1a974527bb986d73afdb62b57ead54e33b54f8ca",
"url": "https://llvm.googlesource.com/llvm-project/+/1a974527bb986d73afdb62b57ead54e33b54f8ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3b66d4a987bff6d9d3e8a0932604cb40850136eb"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3b66d4a987bff6d9d3e8a0932604cb40850136eb",
"title": "3b66d4a987bff6d9d3e8a0932604cb40850136eb",
"url": "https://llvm.googlesource.com/llvm-project/+/3b66d4a987bff6d9d3e8a0932604cb40850136eb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "860b1e68ea180672d0e02fa8328f4a7c45e16f0f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@860b1e68ea180672d0e02fa8328f4a7c45e16f0f",
"title": "860b1e68ea180672d0e02fa8328f4a7c45e16f0f",
"url": "https://llvm.googlesource.com/llvm-project/+/860b1e68ea180672d0e02fa8328f4a7c45e16f0f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a5db2c28443559f5a19f76f9a91ef3280231a969"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a5db2c28443559f5a19f76f9a91ef3280231a969",
"title": "a5db2c28443559f5a19f76f9a91ef3280231a969",
"url": "https://llvm.googlesource.com/llvm-project/+/a5db2c28443559f5a19f76f9a91ef3280231a969"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9bd2aacc68a1c7632abb9410640400dcc09ef50b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9bd2aacc68a1c7632abb9410640400dcc09ef50b",
"title": "9bd2aacc68a1c7632abb9410640400dcc09ef50b",
"url": "https://llvm.googlesource.com/llvm-project/+/9bd2aacc68a1c7632abb9410640400dcc09ef50b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "83dfdd8f5485f6b50213c88f02878f86b3f53852"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@83dfdd8f5485f6b50213c88f02878f86b3f53852",
"title": "83dfdd8f5485f6b50213c88f02878f86b3f53852",
"url": "https://llvm.googlesource.com/llvm-project/+/83dfdd8f5485f6b50213c88f02878f86b3f53852"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "15980624ac516ae2dbbf1f2cd24d63de0f9fd2be"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@15980624ac516ae2dbbf1f2cd24d63de0f9fd2be",
"title": "15980624ac516ae2dbbf1f2cd24d63de0f9fd2be",
"url": "https://llvm.googlesource.com/llvm-project/+/15980624ac516ae2dbbf1f2cd24d63de0f9fd2be"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "15980624ac516ae2dbbf1f2cd24d63de0f9fd2be",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873425765563936576"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-clang-ci-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:06:12.711] Scheduled build:
{
"id": "8707995703700301553",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-29T16:06:12.213669492Z",
"updateTime": "2025-07-29T16:06:12.213669492Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "15980624ac516ae2dbbf1f2cd24d63de0f9fd2be",
"ref": "refs/heads/main"
}
}
}
[16:06:12.711] Task URL: https://cr-buildbucket.appspot.com/build/8707995703700301553
[16:06:12.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:2:0) after 6m36s
[16:06:13.762] Received PubSub notification, asking Buildbucket for the build status
[16:06:13.790] Build status: SCHEDULED
[16:12:48.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:2:0)
[16:12:48.810] Build status: SCHEDULED
[16:12:48.810] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:4:0) after 8m49s
[16:21:37.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:4:0)
[16:21:37.898] Build status: SCHEDULED
[16:21:37.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:5:0) after 6m56s
[16:28:33.924] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:5:0)
[16:28:33.949] Build status: SCHEDULED
[16:28:33.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:6:0) after 9m56s
[16:38:30.042] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:6:0)
[16:38:30.069] Build status: SCHEDULED
[16:38:30.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:7:0) after 4m5s
[16:41:50.811] Received PubSub notification, asking Buildbucket for the build status
[16:41:50.894] Build status: STARTED
[16:42:35.140] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:7:0)
[16:42:35.183] Build status: STARTED
[16:42:35.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:9:0) after 2m45s
[16:45:20.236] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:9:0)
[16:45:20.267] Build status: STARTED
[16:45:20.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:10:0) after 6m47s
[16:52:07.299] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:10:0)
[16:52:07.336] Build status: STARTED
[16:52:07.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:11:0) after 3m55s
[16:56:02.440] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:11:0)
[16:56:02.467] Build status: STARTED
[16:56:02.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:12:0) after 6m40s
[17:02:42.493] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:12:0)
[17:02:42.538] Build status: STARTED
[17:02:42.538] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:13:0) after 6m22s
[17:09:04.710] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:13:0)
[17:09:04.823] Build status: STARTED
[17:09:04.823] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:14:0) after 6m22s
[17:15:26.857] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:14:0)
[17:15:26.875] Build status: STARTED
[17:15:26.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:15:0) after 6m38s
[17:22:04.903] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:15:0)
[17:22:04.922] Build status: STARTED
[17:22:04.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:16:0) after 9m50s
[17:31:54.970] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:16:0)
[17:31:55.007] Build status: STARTED
[17:31:55.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:17:0) after 4m22s
[17:36:17.093] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:17:0)
[17:36:17.112] Build status: STARTED
[17:36:17.112] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:18:0) after 1m58s
[17:38:15.188] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:18:0)
[17:38:15.206] Build status: STARTED
[17:38:15.206] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:19:0) after 5m46s
[17:44:01.264] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:19:0)
[17:44:01.321] Build status: STARTED
[17:44:01.322] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:20:0) after 7m12s
[17:51:13.518] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:20:0)
[17:51:13.542] Build status: STARTED
[17:51:13.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:21:0) after 4m16s
[17:55:29.605] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:21:0)
[17:55:29.634] Build status: STARTED
[17:55:29.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:22:0) after 3m57s
[17:59:26.674] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:22:0)
[17:59:26.696] Build status: STARTED
[17:59:26.697] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:23:0) after 2m7s
[18:01:33.720] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:23:0)
[18:01:33.776] Build status: STARTED
[18:01:33.777] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:24:0) after 4m9s
[18:05:42.804] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:24:0)
[18:05:42.826] Build status: STARTED
[18:05:42.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:25:0) after 7m13s
[18:12:55.820] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:25:0)
[18:12:55.840] Build status: STARTED
[18:12:55.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:26:0) after 2m36s
[18:15:31.847] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:26:0)
[18:15:31.924] Build status: STARTED
[18:15:31.924] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:27:0) after 2m9s
[18:17:40.950] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:27:0)
[18:17:41.087] Build status: STARTED
[18:17:41.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:28:0) after 6m36s
[18:24:17.103] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:28:0)
[18:24:17.138] Build status: STARTED
[18:24:17.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:29:0) after 8m6s
[18:32:23.212] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:29:0)
[18:32:23.227] Build status: STARTED
[18:32:23.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:30:0) after 1m35s
[18:33:58.293] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:30:0)
[18:33:58.316] Build status: STARTED
[18:33:58.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:31:0) after 2m0s
[18:35:58.340] Handling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:31:0)
[18:35:58.399] Build status: STARTED
[18:35:58.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-clang-ci-trigger-40be1bb5:8873425765563936576:32:0) after 4m46s
[18:39:44.914] Received PubSub notification, asking Buildbucket for the build status
[18:39:44.996] Build:
{
"id": "8707995703700301553",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-ci-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-29T16:06:12.213669492Z",
"startTime": "2025-07-29T16:41:50.566766812Z",
"endTime": "2025-07-29T18:39:43.802977463Z",
"updateTime": "2025-07-29T18:39:43.802977463Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "15980624ac516ae2dbbf1f2cd24d63de0f9fd2be",
"ref": "refs/heads/main"
}
}
}
[18:39:44.996] Invocation finished in 2h33m33.943607957s with status SUCCEEDED