[11:16:06.405] New invocation is queued and will start shortly
[11:16:07.529] Starting the invocation (attempt 1)
[11:16:07.553] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6"
[11:16:07.553] Popped gitiles commit info from properties and tags
[11:16:07.553] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:16:07.554] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:16:07.554] Buildbucket request:
{
"requestId": "8905243550877546800",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-linux-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8905243550877546800",
"job": "fuchsia/toolchain.ci-clang-linux-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "89492902d06f40bda54c38bb26cf1e5f6015c726"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@89492902d06f40bda54c38bb26cf1e5f6015c726",
"title": "89492902d06f40bda54c38bb26cf1e5f6015c726",
"url": "https://llvm.googlesource.com/llvm-project/+/89492902d06f40bda54c38bb26cf1e5f6015c726"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "70feafdb27b45018f5f72e8f1359fdf9889c3f2a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@70feafdb27b45018f5f72e8f1359fdf9889c3f2a",
"title": "70feafdb27b45018f5f72e8f1359fdf9889c3f2a",
"url": "https://llvm.googlesource.com/llvm-project/+/70feafdb27b45018f5f72e8f1359fdf9889c3f2a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2ad3bcded84be3bdbddca9698afe2614a4d7916b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2ad3bcded84be3bdbddca9698afe2614a4d7916b",
"title": "2ad3bcded84be3bdbddca9698afe2614a4d7916b",
"url": "https://llvm.googlesource.com/llvm-project/+/2ad3bcded84be3bdbddca9698afe2614a4d7916b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1c764b952a1c9aa6c832cfc27aff09fc067304f2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1c764b952a1c9aa6c832cfc27aff09fc067304f2",
"title": "1c764b952a1c9aa6c832cfc27aff09fc067304f2",
"url": "https://llvm.googlesource.com/llvm-project/+/1c764b952a1c9aa6c832cfc27aff09fc067304f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "afe019ca93a72a5969d82cfff5018f3dd79dc75a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@afe019ca93a72a5969d82cfff5018f3dd79dc75a",
"title": "afe019ca93a72a5969d82cfff5018f3dd79dc75a",
"url": "https://llvm.googlesource.com/llvm-project/+/afe019ca93a72a5969d82cfff5018f3dd79dc75a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "05b75e006bf20638aa56cd7bc282d44512aa915e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@05b75e006bf20638aa56cd7bc282d44512aa915e",
"title": "05b75e006bf20638aa56cd7bc282d44512aa915e",
"url": "https://llvm.googlesource.com/llvm-project/+/05b75e006bf20638aa56cd7bc282d44512aa915e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f86da4cb7d6433dab10a91e33b4c24e87ec799d8"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f86da4cb7d6433dab10a91e33b4c24e87ec799d8",
"title": "f86da4cb7d6433dab10a91e33b4c24e87ec799d8",
"url": "https://llvm.googlesource.com/llvm-project/+/f86da4cb7d6433dab10a91e33b4c24e87ec799d8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6",
"title": "0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6",
"url": "https://llvm.googlesource.com/llvm-project/+/0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8905243550877546800"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-linux-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:16:08.654] Scheduled build:
{
"id": "8739813488745676641",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-linux-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-08-12T11:16:07.810039761Z",
"updateTime": "2024-08-12T11:16:07.810039761Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6",
"ref": "refs/heads/main"
}
}
}
[11:16:08.654] Task URL: https://cr-buildbucket.appspot.com/build/8739813488745676641
[11:16:08.654] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:2:0) after 4m36s
[11:16:08.751] Received PubSub notification, asking Buildbucket for the build status
[11:16:08.806] Build status: SCHEDULED
[11:16:14.561] Received PubSub notification, asking Buildbucket for the build status
[11:16:14.583] Build status: STARTED
[11:20:44.672] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:2:0)
[11:20:44.734] Build status: STARTED
[11:20:44.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:5:0) after 3m9s
[11:23:53.767] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:5:0)
[11:23:53.872] Build status: STARTED
[11:23:53.872] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:6:0) after 3m52s
[11:27:45.932] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:6:0)
[11:27:45.980] Build status: STARTED
[11:27:45.980] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:7:0) after 4m20s
[11:32:05.978] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:7:0)
[11:32:05.997] Build status: STARTED
[11:32:05.997] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:8:0) after 7m2s
[11:39:08.033] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:8:0)
[11:39:08.090] Build status: STARTED
[11:39:08.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:9:0) after 6m3s
[11:45:11.096] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:9:0)
[11:45:11.132] Build status: STARTED
[11:45:11.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8905243550877546800:10:0) after 3m45s
[11:46:41.591] Received PubSub notification, asking Buildbucket for the build status
[11:46:41.633] Build:
{
"id": "8739813488745676641",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-linux-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-08-12T11:16:07.810039761Z",
"startTime": "2024-08-12T11:16:14.265397567Z",
"endTime": "2024-08-12T11:46:41.270870623Z",
"updateTime": "2024-08-12T11:46:41.270870623Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "0ea9cdbf50b6dbd31686c2fefd66e3348eb4a9d6",
"ref": "refs/heads/main"
}
}
}
[11:46:41.633] Invocation finished in 30m35.239839362s with status SUCCEEDED