[13:48:06.081] New invocation is queued and will start shortly
[13:48:07.249] Starting the invocation (attempt 1)
[13:48:07.288] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/c1b997464bb68ae37c9ed9b5594cb440c8a13382"
[13:48:07.288] Popped gitiles commit info from properties and tags
[13:48:07.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[13:48:07.288] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[13:48:07.288] Buildbucket request:
{
"requestId": "8934859196211317792",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8934859196211317792",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "73dcbd411b4573a4283d30307e48fde0f84423e5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@73dcbd411b4573a4283d30307e48fde0f84423e5",
"title": "73dcbd411b4573a4283d30307e48fde0f84423e5",
"url": "https://llvm.googlesource.com/llvm-project/+/73dcbd411b4573a4283d30307e48fde0f84423e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4f330b7f756e3244f7b7a9a654ea153c23d80319"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4f330b7f756e3244f7b7a9a654ea153c23d80319",
"title": "4f330b7f756e3244f7b7a9a654ea153c23d80319",
"url": "https://llvm.googlesource.com/llvm-project/+/4f330b7f756e3244f7b7a9a654ea153c23d80319"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "356494c36e716e3bf7820fa4e87ac732b4d87a5a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@356494c36e716e3bf7820fa4e87ac732b4d87a5a",
"title": "356494c36e716e3bf7820fa4e87ac732b4d87a5a",
"url": "https://llvm.googlesource.com/llvm-project/+/356494c36e716e3bf7820fa4e87ac732b4d87a5a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "24f437f31e0b4bcf9e55e55ebe986e544d89e5cd"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@24f437f31e0b4bcf9e55e55ebe986e544d89e5cd",
"title": "24f437f31e0b4bcf9e55e55ebe986e544d89e5cd",
"url": "https://llvm.googlesource.com/llvm-project/+/24f437f31e0b4bcf9e55e55ebe986e544d89e5cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "57a554800b804a0d849858972822142e1f75d37f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@57a554800b804a0d849858972822142e1f75d37f",
"title": "57a554800b804a0d849858972822142e1f75d37f",
"url": "https://llvm.googlesource.com/llvm-project/+/57a554800b804a0d849858972822142e1f75d37f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ba727ac2199c3c1cecfdaaa487cca0ffc29d2e64"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ba727ac2199c3c1cecfdaaa487cca0ffc29d2e64",
"title": "ba727ac2199c3c1cecfdaaa487cca0ffc29d2e64",
"url": "https://llvm.googlesource.com/llvm-project/+/ba727ac2199c3c1cecfdaaa487cca0ffc29d2e64"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "450dfab8c36337813814a69d0c50df5bc74b9419"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@450dfab8c36337813814a69d0c50df5bc74b9419",
"title": "450dfab8c36337813814a69d0c50df5bc74b9419",
"url": "https://llvm.googlesource.com/llvm-project/+/450dfab8c36337813814a69d0c50df5bc74b9419"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "765b38fbf16bef0a0646a4bfc9a933cb8d11f5ba"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@765b38fbf16bef0a0646a4bfc9a933cb8d11f5ba",
"title": "765b38fbf16bef0a0646a4bfc9a933cb8d11f5ba",
"url": "https://llvm.googlesource.com/llvm-project/+/765b38fbf16bef0a0646a4bfc9a933cb8d11f5ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e88a64f7aba42d61289b9cefe3aecd9320ee640a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e88a64f7aba42d61289b9cefe3aecd9320ee640a",
"title": "e88a64f7aba42d61289b9cefe3aecd9320ee640a",
"url": "https://llvm.googlesource.com/llvm-project/+/e88a64f7aba42d61289b9cefe3aecd9320ee640a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "65341b09b0d54ce5318e26a63b84138695d2ac35"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@65341b09b0d54ce5318e26a63b84138695d2ac35",
"title": "65341b09b0d54ce5318e26a63b84138695d2ac35",
"url": "https://llvm.googlesource.com/llvm-project/+/65341b09b0d54ce5318e26a63b84138695d2ac35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "632d13ce84a806717ef367e3dd44640bde7b7d7e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@632d13ce84a806717ef367e3dd44640bde7b7d7e",
"title": "632d13ce84a806717ef367e3dd44640bde7b7d7e",
"url": "https://llvm.googlesource.com/llvm-project/+/632d13ce84a806717ef367e3dd44640bde7b7d7e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "cf1d2e4f94b8257191ee932ded7b27540cfa02df"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@cf1d2e4f94b8257191ee932ded7b27540cfa02df",
"title": "cf1d2e4f94b8257191ee932ded7b27540cfa02df",
"url": "https://llvm.googlesource.com/llvm-project/+/cf1d2e4f94b8257191ee932ded7b27540cfa02df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ed65ced22af8a31ab374179d3d56b1e7c1456069"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ed65ced22af8a31ab374179d3d56b1e7c1456069",
"title": "ed65ced22af8a31ab374179d3d56b1e7c1456069",
"url": "https://llvm.googlesource.com/llvm-project/+/ed65ced22af8a31ab374179d3d56b1e7c1456069"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c1b997464bb68ae37c9ed9b5594cb440c8a13382"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c1b997464bb68ae37c9ed9b5594cb440c8a13382",
"title": "c1b997464bb68ae37c9ed9b5594cb440c8a13382",
"url": "https://llvm.googlesource.com/llvm-project/+/c1b997464bb68ae37c9ed9b5594cb440c8a13382"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c1b997464bb68ae37c9ed9b5594cb440c8a13382",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934859196211317792"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[13:48:07.861] Scheduled build:
{
"id": "8769429134192152753",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-20T13:48:07.384031487Z",
"updateTime": "2023-09-20T13:48:07.384031487Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c1b997464bb68ae37c9ed9b5594cb440c8a13382",
"ref": "refs/heads/main"
}
}
}
[13:48:07.861] Task URL: https://cr-buildbucket.appspot.com/build/8769429134192152753
[13:48:07.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:2:0) after 8m7s
[13:48:08.750] Received PubSub notification, asking Buildbucket for the build status
[13:48:08.797] Build status: SCHEDULED
[13:48:40.711] Received PubSub notification, asking Buildbucket for the build status
[13:48:40.737] Build status: STARTED
[13:56:14.863] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:2:0)
[13:56:14.894] Build status: STARTED
[13:56:14.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:5:0) after 5m51s
[14:02:05.913] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:5:0)
[14:02:05.936] Build status: STARTED
[14:02:05.936] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:6:0) after 7m48s
[14:09:54.032] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:6:0)
[14:09:54.050] Build status: STARTED
[14:09:54.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:7:0) after 9m28s
[14:19:22.073] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:7:0)
[14:19:22.097] Build status: STARTED
[14:19:22.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:8:0) after 5m53s
[14:25:15.294] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:8:0)
[14:25:15.326] Build status: STARTED
[14:25:15.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:9:0) after 3m54s
[14:29:09.635] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:9:0)
[14:29:09.691] Build status: STARTED
[14:29:09.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:10:0) after 3m45s
[14:32:55.246] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:10:0)
[14:32:55.318] Build status: STARTED
[14:32:55.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:11:0) after 4m1s
[14:36:56.572] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:11:0)
[14:36:56.595] Build status: STARTED
[14:36:56.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:12:0) after 7m40s
[14:44:36.694] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:12:0)
[14:44:36.739] Build status: STARTED
[14:44:36.739] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:13:0) after 1m22s
[14:45:59.094] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:13:0)
[14:45:59.117] Build status: STARTED
[14:45:59.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:14:0) after 7m19s
[14:53:18.147] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:14:0)
[14:53:18.198] Build status: STARTED
[14:53:18.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934859196211317792:15:0) after 4m30s
[14:56:02.797] Received PubSub notification, asking Buildbucket for the build status
[14:56:02.833] Build:
{
"id": "8769429134192152753",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-20T13:48:07.384031487Z",
"startTime": "2023-09-20T13:48:39.771565Z",
"endTime": "2023-09-20T14:56:01.780443144Z",
"updateTime": "2023-09-20T14:56:01.780443144Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c1b997464bb68ae37c9ed9b5594cb440c8a13382",
"ref": "refs/heads/main"
}
}
}
[14:56:02.833] Invocation finished in 1h7m56.758695731s with status SUCCEEDED