[18:38:36.142] New invocation is queued and will start shortly
[18:38:37.229] Starting the invocation (attempt 1)
[18:38:37.256] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba"
[18:38:37.256] Popped gitiles commit info from properties and tags
[18:38:37.256] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:38:37.257] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:38:37.258] Buildbucket request:
{
"requestId": "8875046921643994016",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8875046921643994016",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b9d8d1e4167699de3027ceac94d29253162158c3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b9d8d1e4167699de3027ceac94d29253162158c3",
"title": "b9d8d1e4167699de3027ceac94d29253162158c3",
"url": "https://llvm.googlesource.com/llvm-project/+/b9d8d1e4167699de3027ceac94d29253162158c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "df10df8b0caec9e649dd8c4415610038b7926060"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@df10df8b0caec9e649dd8c4415610038b7926060",
"title": "df10df8b0caec9e649dd8c4415610038b7926060",
"url": "https://llvm.googlesource.com/llvm-project/+/df10df8b0caec9e649dd8c4415610038b7926060"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a0fcb50bf99d1d48458ee27ca92e5cd54e9910d5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a0fcb50bf99d1d48458ee27ca92e5cd54e9910d5",
"title": "a0fcb50bf99d1d48458ee27ca92e5cd54e9910d5",
"url": "https://llvm.googlesource.com/llvm-project/+/a0fcb50bf99d1d48458ee27ca92e5cd54e9910d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "53183be294e92093a37f3875a8c586c74fbd4d7c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@53183be294e92093a37f3875a8c586c74fbd4d7c",
"title": "53183be294e92093a37f3875a8c586c74fbd4d7c",
"url": "https://llvm.googlesource.com/llvm-project/+/53183be294e92093a37f3875a8c586c74fbd4d7c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bfd6bb1641109ec5e2eb284565656a7c0b493f89"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bfd6bb1641109ec5e2eb284565656a7c0b493f89",
"title": "bfd6bb1641109ec5e2eb284565656a7c0b493f89",
"url": "https://llvm.googlesource.com/llvm-project/+/bfd6bb1641109ec5e2eb284565656a7c0b493f89"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6dc193a8c0dfd06be434eff1a544e727cc9876a1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6dc193a8c0dfd06be434eff1a544e727cc9876a1",
"title": "6dc193a8c0dfd06be434eff1a544e727cc9876a1",
"url": "https://llvm.googlesource.com/llvm-project/+/6dc193a8c0dfd06be434eff1a544e727cc9876a1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3ab95e434428661f16c3c4bb416e7f97f926fa50"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3ab95e434428661f16c3c4bb416e7f97f926fa50",
"title": "3ab95e434428661f16c3c4bb416e7f97f926fa50",
"url": "https://llvm.googlesource.com/llvm-project/+/3ab95e434428661f16c3c4bb416e7f97f926fa50"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "44baef98431e21309a4b364fa7b3704a7b687ad6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@44baef98431e21309a4b364fa7b3704a7b687ad6",
"title": "44baef98431e21309a4b364fa7b3704a7b687ad6",
"url": "https://llvm.googlesource.com/llvm-project/+/44baef98431e21309a4b364fa7b3704a7b687ad6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6563c795cd3f4fdfaba644897dada97be8f57e5b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6563c795cd3f4fdfaba644897dada97be8f57e5b",
"title": "6563c795cd3f4fdfaba644897dada97be8f57e5b",
"url": "https://llvm.googlesource.com/llvm-project/+/6563c795cd3f4fdfaba644897dada97be8f57e5b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bf94c8ddb321696956365830bf23dd232ef90e74"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bf94c8ddb321696956365830bf23dd232ef90e74",
"title": "bf94c8ddb321696956365830bf23dd232ef90e74",
"url": "https://llvm.googlesource.com/llvm-project/+/bf94c8ddb321696956365830bf23dd232ef90e74"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "67588d3075f3dc05b4a08c04ed5f4203e9855519"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@67588d3075f3dc05b4a08c04ed5f4203e9855519",
"title": "67588d3075f3dc05b4a08c04ed5f4203e9855519",
"url": "https://llvm.googlesource.com/llvm-project/+/67588d3075f3dc05b4a08c04ed5f4203e9855519"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4b738387aeba477aa3c7c7d688fa9982cf1f2722"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4b738387aeba477aa3c7c7d688fa9982cf1f2722",
"title": "4b738387aeba477aa3c7c7d688fa9982cf1f2722",
"url": "https://llvm.googlesource.com/llvm-project/+/4b738387aeba477aa3c7c7d688fa9982cf1f2722"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba",
"title": "ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba",
"url": "https://llvm.googlesource.com/llvm-project/+/ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8875046921643994016"
},
{
"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"
}
}
[18:38:37.743] Scheduled build:
{
"id": "8709616859729138417",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-11T18:38:37.345071235Z",
"updateTime": "2025-07-11T18:38:37.345071235Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba",
"ref": "refs/heads/main"
}
}
}
[18:38:37.743] Task URL: https://cr-buildbucket.appspot.com/build/8709616859729138417
[18:38:37.743] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:2:0) after 5m35s
[18:38:38.683] Received PubSub notification, asking Buildbucket for the build status
[18:38:38.758] Build status: SCHEDULED
[18:44:12.814] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:2:0)
[18:44:12.849] Build status: SCHEDULED
[18:44:12.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:4:0) after 1m43s
[18:45:55.995] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:4:0)
[18:45:56.024] Build status: SCHEDULED
[18:45:56.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:5:0) after 3m27s
[18:49:23.045] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:5:0)
[18:49:23.073] Build status: SCHEDULED
[18:49:23.074] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:6:0) after 1m43s
[18:51:06.105] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:6:0)
[18:51:06.126] Build status: SCHEDULED
[18:51:06.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:7:0) after 9m26s
[19:00:32.208] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:7:0)
[19:00:32.238] Build status: SCHEDULED
[19:00:32.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:8:0) after 5m38s
[19:06:10.474] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:8:0)
[19:06:10.501] Build status: SCHEDULED
[19:06:10.501] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:9:0) after 9m42s
[19:15:52.520] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:9:0)
[19:15:52.548] Build status: SCHEDULED
[19:15:52.548] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:10:0) after 5m30s
[19:21:22.568] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:10:0)
[19:21:22.592] Build status: SCHEDULED
[19:21:22.592] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:11:0) after 1m7s
[19:22:29.651] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:11:0)
[19:22:29.677] Build status: SCHEDULED
[19:22:29.677] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:12:0) after 5m40s
[19:28:09.701] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:12:0)
[19:28:09.724] Build status: SCHEDULED
[19:28:09.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:13:0) after 1m55s
[19:30:03.803] Received PubSub notification, asking Buildbucket for the build status
[19:30:03.834] Build status: STARTED
[19:30:04.818] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:13:0)
[19:30:04.844] Build status: STARTED
[19:30:04.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:15:0) after 1m50s
[19:31:54.889] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:15:0)
[19:31:54.915] Build status: STARTED
[19:31:54.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:16:0) after 4m53s
[19:36:47.953] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:16:0)
[19:36:47.977] Build status: STARTED
[19:36:47.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:17:0) after 5m41s
[19:42:29.031] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:17:0)
[19:42:29.086] Build status: STARTED
[19:42:29.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:18:0) after 2m40s
[19:45:09.169] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:18:0)
[19:45:09.201] Build status: STARTED
[19:45:09.201] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:19:0) after 6m9s
[19:51:18.226] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:19:0)
[19:51:18.243] Build status: STARTED
[19:51:18.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:20:0) after 1m21s
[19:52:39.237] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:20:0)
[19:52:39.253] Build status: STARTED
[19:52:39.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:21:0) after 5m49s
[19:58:28.320] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:21:0)
[19:58:28.341] Build status: STARTED
[19:58:28.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:22:0) after 5m5s
[20:03:33.356] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:22:0)
[20:03:33.386] Build status: STARTED
[20:03:33.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:23:0) after 5m32s
[20:09:05.416] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:23:0)
[20:09:05.432] Build status: STARTED
[20:09:05.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:24:0) after 1m37s
[20:10:42.472] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:24:0)
[20:10:42.503] Build status: STARTED
[20:10:42.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:25:0) after 2m1s
[20:12:43.524] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:25:0)
[20:12:43.543] Build status: STARTED
[20:12:43.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:26:0) after 6m18s
[20:19:01.625] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:26:0)
[20:19:01.644] Build status: STARTED
[20:19:01.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:27:0) after 8m16s
[20:27:17.858] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:27:0)
[20:27:17.875] Build status: STARTED
[20:27:17.875] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:28:0) after 9m9s
[20:36:26.901] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:28:0)
[20:36:26.922] Build status: STARTED
[20:36:26.922] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:29:0) after 2m39s
[20:39:05.944] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:29:0)
[20:39:05.962] Build status: STARTED
[20:39:05.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:30:0) after 5m42s
[20:44:47.984] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:30:0)
[20:44:48.054] Build status: STARTED
[20:44:48.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:31:0) after 5m21s
[20:50:09.083] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:31:0)
[20:50:09.104] Build status: STARTED
[20:50:09.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:32:0) after 2m10s
[20:52:19.115] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:32:0)
[20:52:19.134] Build status: STARTED
[20:52:19.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:33:0) after 2m25s
[20:54:44.202] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:33:0)
[20:54:44.232] Build status: STARTED
[20:54:44.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:34:0) after 2m43s
[20:57:27.253] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:34:0)
[20:57:27.278] Build status: STARTED
[20:57:27.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8875046921643994016:35:0) after 2m30s
[20:59:16.840] Received PubSub notification, asking Buildbucket for the build status
[20:59:16.863] Build:
{
"id": "8709616859729138417",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-11T18:38:37.345071235Z",
"startTime": "2025-07-11T19:30:03.600704133Z",
"endTime": "2025-07-11T20:59:16.531184503Z",
"updateTime": "2025-07-11T20:59:16.531184503Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "ac39d26dc4a8bf442ad59d0df742ae38a4ff9aba",
"ref": "refs/heads/main"
}
}
}
[20:59:16.863] Invocation finished in 2h20m40.728061488s with status SUCCEEDED