[11:44:14.816] New invocation is queued and will start shortly
[11:44:15.910] Starting the invocation (attempt 1)
[11:44:15.935] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/2a4e61b342e7a19bcb229ef16dee083c58224a3f"
[11:44:15.935] Popped gitiles commit info from properties and tags
[11:44:15.935] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:44:15.935] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:44:15.935] Buildbucket request:
{
"requestId": "8915751028554153168",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8915751028554153168",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "750de326ef0408df4a5f91773c355ded1a1b1b77"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@750de326ef0408df4a5f91773c355ded1a1b1b77",
"title": "750de326ef0408df4a5f91773c355ded1a1b1b77",
"url": "https://llvm.googlesource.com/llvm-project/+/750de326ef0408df4a5f91773c355ded1a1b1b77"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d17db6066d2524856fab493dd894f8396e896bc7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d17db6066d2524856fab493dd894f8396e896bc7",
"title": "d17db6066d2524856fab493dd894f8396e896bc7",
"url": "https://llvm.googlesource.com/llvm-project/+/d17db6066d2524856fab493dd894f8396e896bc7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3da065896b1b59fd8291958e8d13f4a942d51214"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3da065896b1b59fd8291958e8d13f4a942d51214",
"title": "3da065896b1b59fd8291958e8d13f4a942d51214",
"url": "https://llvm.googlesource.com/llvm-project/+/3da065896b1b59fd8291958e8d13f4a942d51214"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8d6a9c05f6d676c93c84ebf06cf6263657e74c00"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8d6a9c05f6d676c93c84ebf06cf6263657e74c00",
"title": "8d6a9c05f6d676c93c84ebf06cf6263657e74c00",
"url": "https://llvm.googlesource.com/llvm-project/+/8d6a9c05f6d676c93c84ebf06cf6263657e74c00"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ac39fa740b067f6197dca1caecc97c0da91ebf3d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ac39fa740b067f6197dca1caecc97c0da91ebf3d",
"title": "ac39fa740b067f6197dca1caecc97c0da91ebf3d",
"url": "https://llvm.googlesource.com/llvm-project/+/ac39fa740b067f6197dca1caecc97c0da91ebf3d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c26e9bf8fafea656b64d811c01fc3a5f970829ef"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c26e9bf8fafea656b64d811c01fc3a5f970829ef",
"title": "c26e9bf8fafea656b64d811c01fc3a5f970829ef",
"url": "https://llvm.googlesource.com/llvm-project/+/c26e9bf8fafea656b64d811c01fc3a5f970829ef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9462abdff1687c1e35574cdef8de6c549addc42a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9462abdff1687c1e35574cdef8de6c549addc42a",
"title": "9462abdff1687c1e35574cdef8de6c549addc42a",
"url": "https://llvm.googlesource.com/llvm-project/+/9462abdff1687c1e35574cdef8de6c549addc42a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2a4e61b342e7a19bcb229ef16dee083c58224a3f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2a4e61b342e7a19bcb229ef16dee083c58224a3f",
"title": "2a4e61b342e7a19bcb229ef16dee083c58224a3f",
"url": "https://llvm.googlesource.com/llvm-project/+/2a4e61b342e7a19bcb229ef16dee083c58224a3f"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2a4e61b342e7a19bcb229ef16dee083c58224a3f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8915751028554153168"
},
{
"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"
}
}
[11:44:16.367] Scheduled build:
{
"id": "8750320966652883745",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-18T11:44:16.000590005Z",
"updateTime": "2024-04-18T11:44:16.000590005Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2a4e61b342e7a19bcb229ef16dee083c58224a3f",
"ref": "refs/heads/main"
}
}
}
[11:44:16.367] Task URL: https://cr-buildbucket.appspot.com/build/8750320966652883745
[11:44:16.367] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:2:0) after 4m36s
[11:44:17.448] Received PubSub notification, asking Buildbucket for the build status
[11:44:17.480] Build status: SCHEDULED
[11:44:27.068] Received PubSub notification, asking Buildbucket for the build status
[11:44:27.110] Build status: STARTED
[11:48:52.691] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:2:0)
[11:48:52.724] Build status: STARTED
[11:48:52.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:5:0) after 6m21s
[11:55:13.741] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:5:0)
[11:55:13.830] Build status: STARTED
[11:55:13.830] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:6:0) after 2m10s
[11:57:23.849] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:6:0)
[11:57:23.895] Build status: STARTED
[11:57:23.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:7:0) after 7m47s
[12:05:10.972] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:7:0)
[12:05:11.029] Build status: STARTED
[12:05:11.030] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:8:0) after 3m24s
[12:08:35.096] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:8:0)
[12:08:35.123] Build status: STARTED
[12:08:35.123] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:9:0) after 4m33s
[12:13:08.590] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:9:0)
[12:13:08.613] Build status: STARTED
[12:13:08.613] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:10:0) after 7m47s
[12:20:55.853] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:10:0)
[12:20:55.875] Build status: STARTED
[12:20:55.876] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:11:0) after 5m50s
[12:26:45.920] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:11:0)
[12:26:46.183] Build status: STARTED
[12:26:46.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:12:0) after 5m34s
[12:32:20.258] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:12:0)
[12:32:20.277] Build status: STARTED
[12:32:20.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:13:0) after 9m12s
[12:41:32.613] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:13:0)
[12:41:32.766] Build status: STARTED
[12:41:32.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:14:0) after 9m5s
[12:50:37.999] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:14:0)
[12:50:38.114] Build status: STARTED
[12:50:38.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:15:0) after 5m29s
[12:56:07.152] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:15:0)
[12:56:07.255] Build status: STARTED
[12:56:07.255] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8915751028554153168:16:0) after 9m2s
[12:57:14.916] Received PubSub notification, asking Buildbucket for the build status
[12:57:14.942] Build:
{
"id": "8750320966652883745",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-04-18T11:44:16.000590005Z",
"startTime": "2024-04-18T11:44:26.646499662Z",
"endTime": "2024-04-18T12:57:14.540906125Z",
"updateTime": "2024-04-18T12:57:14.540906125Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2a4e61b342e7a19bcb229ef16dee083c58224a3f",
"ref": "refs/heads/main"
}
}
}
[12:57:14.942] Invocation finished in 1h13m0.138855312s with status SUCCEEDED