[21:53:04.965] New invocation is queued and will start shortly
[21:53:06.049] Starting the invocation (attempt 1)
[21:53:06.077] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/4b52d221a09b71c9dc0c0bb842ec706de663167c"
[21:53:06.077] Popped gitiles commit info from properties and tags
[21:53:06.077] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:53:06.077] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:53:06.077] Buildbucket request:
{
"requestId": "8874762895102939120",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-host-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8874762895102939120",
"job": "fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ada514b0af120e92b4d94653f8e272d6cec9f321"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ada514b0af120e92b4d94653f8e272d6cec9f321",
"title": "ada514b0af120e92b4d94653f8e272d6cec9f321",
"url": "https://llvm.googlesource.com/llvm-project/+/ada514b0af120e92b4d94653f8e272d6cec9f321"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2464313eef01c5b1edf0eccf57a32cdee01472c7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2464313eef01c5b1edf0eccf57a32cdee01472c7",
"title": "2464313eef01c5b1edf0eccf57a32cdee01472c7",
"url": "https://llvm.googlesource.com/llvm-project/+/2464313eef01c5b1edf0eccf57a32cdee01472c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "92ef8e3419ee316089501a79446151c895902681"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@92ef8e3419ee316089501a79446151c895902681",
"title": "92ef8e3419ee316089501a79446151c895902681",
"url": "https://llvm.googlesource.com/llvm-project/+/92ef8e3419ee316089501a79446151c895902681"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "40ceaf1d995c22a3fab3e1c5df4651cf7d1e895c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@40ceaf1d995c22a3fab3e1c5df4651cf7d1e895c",
"title": "40ceaf1d995c22a3fab3e1c5df4651cf7d1e895c",
"url": "https://llvm.googlesource.com/llvm-project/+/40ceaf1d995c22a3fab3e1c5df4651cf7d1e895c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "45fa0b29bc33ca6619543139792fa38204df2731"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@45fa0b29bc33ca6619543139792fa38204df2731",
"title": "45fa0b29bc33ca6619543139792fa38204df2731",
"url": "https://llvm.googlesource.com/llvm-project/+/45fa0b29bc33ca6619543139792fa38204df2731"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "834591e062d27db2b66aa37aef2e917e0af1e6ff"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@834591e062d27db2b66aa37aef2e917e0af1e6ff",
"title": "834591e062d27db2b66aa37aef2e917e0af1e6ff",
"url": "https://llvm.googlesource.com/llvm-project/+/834591e062d27db2b66aa37aef2e917e0af1e6ff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0d5325bb203fe92c75f47b473d1f77d9d4f4a5d0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0d5325bb203fe92c75f47b473d1f77d9d4f4a5d0",
"title": "0d5325bb203fe92c75f47b473d1f77d9d4f4a5d0",
"url": "https://llvm.googlesource.com/llvm-project/+/0d5325bb203fe92c75f47b473d1f77d9d4f4a5d0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4b52d221a09b71c9dc0c0bb842ec706de663167c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4b52d221a09b71c9dc0c0bb842ec706de663167c",
"title": "4b52d221a09b71c9dc0c0bb842ec706de663167c",
"url": "https://llvm.googlesource.com/llvm-project/+/4b52d221a09b71c9dc0c0bb842ec706de663167c"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "4b52d221a09b71c9dc0c0bb842ec706de663167c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8874762895102939120"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:53:06.701] Scheduled build:
{
"id": "8709332833212058401",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-host-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-14T21:53:06.139201469Z",
"updateTime": "2025-07-14T21:53:06.139201469Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "4b52d221a09b71c9dc0c0bb842ec706de663167c",
"ref": "refs/heads/main"
}
}
}
[21:53:06.701] Task URL: https://cr-buildbucket.appspot.com/build/8709332833212058401
[21:53:06.701] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:2:0) after 3m33s
[21:53:07.561] Received PubSub notification, asking Buildbucket for the build status
[21:53:07.645] Build status: SCHEDULED
[21:56:39.679] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:2:0)
[21:56:39.726] Build status: SCHEDULED
[21:56:39.726] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:4:0) after 4m5s
[22:00:44.745] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:4:0)
[22:00:44.842] Build status: SCHEDULED
[22:00:44.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:5:0) after 1m1s
[22:01:45.843] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:5:0)
[22:01:45.935] Build status: SCHEDULED
[22:01:45.935] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:6:0) after 9m38s
[22:11:24.063] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:6:0)
[22:11:24.097] Build status: SCHEDULED
[22:11:24.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:7:0) after 3m6s
[22:14:30.121] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:7:0)
[22:14:30.171] Build status: SCHEDULED
[22:14:30.171] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:8:0) after 8m29s
[22:22:59.203] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:8:0)
[22:22:59.230] Build status: SCHEDULED
[22:22:59.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:9:0) after 7m6s
[22:24:55.731] Received PubSub notification, asking Buildbucket for the build status
[22:24:55.750] Build status: STARTED
[22:30:05.397] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:9:0)
[22:30:05.423] Build status: STARTED
[22:30:05.423] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:11:0) after 3m27s
[22:33:32.462] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:11:0)
[22:33:32.483] Build status: STARTED
[22:33:32.483] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:12:0) after 4m59s
[22:38:31.538] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:12:0)
[22:38:31.560] Build status: STARTED
[22:38:31.560] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:13:0) after 4m59s
[22:43:30.740] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:13:0)
[22:43:30.824] Build status: STARTED
[22:43:30.824] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:14:0) after 8m17s
[22:51:47.868] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:14:0)
[22:51:47.889] Build status: STARTED
[22:51:47.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:15:0) after 8m37s
[23:00:24.927] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:15:0)
[23:00:24.982] Build status: STARTED
[23:00:24.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-host-mac-x64-40be1bb5:8874762895102939120:16:0) after 3m46s
[23:03:10.863] Received PubSub notification, asking Buildbucket for the build status
[23:03:10.891] Build:
{
"id": "8709332833212058401",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-host-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-14T21:53:06.139201469Z",
"startTime": "2025-07-14T22:24:55.412680566Z",
"endTime": "2025-07-14T23:03:08.201606963Z",
"updateTime": "2025-07-14T23:03:08.201606963Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "4b52d221a09b71c9dc0c0bb842ec706de663167c",
"ref": "refs/heads/main"
}
}
}
[23:03:10.891] Invocation finished in 1h10m5.938596559s with status SUCCEEDED