[20:58:40.087] New invocation is queued and will start shortly
[20:58:41.148] Starting the invocation (attempt 1)
[20:58:41.178] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/1bf6f55911ca6427789093b1df8a63d7e72dac51"
[20:58:41.178] Popped gitiles commit info from properties and tags
[20:58:41.178] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:58:41.178] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:58:41.178] Buildbucket request:
{
"requestId": "8939724343477654352",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939724343477654352",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4a3c865d8ed2fc60170495bc6e6e2a9356e5faf2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4a3c865d8ed2fc60170495bc6e6e2a9356e5faf2",
"title": "4a3c865d8ed2fc60170495bc6e6e2a9356e5faf2",
"url": "https://llvm.googlesource.com/llvm-project/+/4a3c865d8ed2fc60170495bc6e6e2a9356e5faf2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2a859b20146108af84c741a509dc0e534e045768"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2a859b20146108af84c741a509dc0e534e045768",
"title": "2a859b20146108af84c741a509dc0e534e045768",
"url": "https://llvm.googlesource.com/llvm-project/+/2a859b20146108af84c741a509dc0e534e045768"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ca18b4a0c1324ae679490add3ad3bbd2077b804f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ca18b4a0c1324ae679490add3ad3bbd2077b804f",
"title": "ca18b4a0c1324ae679490add3ad3bbd2077b804f",
"url": "https://llvm.googlesource.com/llvm-project/+/ca18b4a0c1324ae679490add3ad3bbd2077b804f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c57d249cd917324917c4b56174ade3c4fbae57a6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c57d249cd917324917c4b56174ade3c4fbae57a6",
"title": "c57d249cd917324917c4b56174ade3c4fbae57a6",
"url": "https://llvm.googlesource.com/llvm-project/+/c57d249cd917324917c4b56174ade3c4fbae57a6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e346fd8a60d4969b29bdd1740a89b1ea43635331"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e346fd8a60d4969b29bdd1740a89b1ea43635331",
"title": "e346fd8a60d4969b29bdd1740a89b1ea43635331",
"url": "https://llvm.googlesource.com/llvm-project/+/e346fd8a60d4969b29bdd1740a89b1ea43635331"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "cd1b8be8de91bc1c43bac3eea7ebf3b5643b031c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@cd1b8be8de91bc1c43bac3eea7ebf3b5643b031c",
"title": "cd1b8be8de91bc1c43bac3eea7ebf3b5643b031c",
"url": "https://llvm.googlesource.com/llvm-project/+/cd1b8be8de91bc1c43bac3eea7ebf3b5643b031c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e777e44546f903146e7cfcd241a9dd9d7f217865"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e777e44546f903146e7cfcd241a9dd9d7f217865",
"title": "e777e44546f903146e7cfcd241a9dd9d7f217865",
"url": "https://llvm.googlesource.com/llvm-project/+/e777e44546f903146e7cfcd241a9dd9d7f217865"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1bf6f55911ca6427789093b1df8a63d7e72dac51"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1bf6f55911ca6427789093b1df8a63d7e72dac51",
"title": "1bf6f55911ca6427789093b1df8a63d7e72dac51",
"url": "https://llvm.googlesource.com/llvm-project/+/1bf6f55911ca6427789093b1df8a63d7e72dac51"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1bf6f55911ca6427789093b1df8a63d7e72dac51",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939724343477654352"
},
{
"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"
}
}
[20:58:41.972] Scheduled build:
{
"id": "8774294281621175969",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-07-28T20:58:41.235987377Z",
"updateTime": "2023-07-28T20:58:41.235987377Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1bf6f55911ca6427789093b1df8a63d7e72dac51",
"ref": "refs/heads/main"
}
}
}
[20:58:41.972] Task URL: https://cr-buildbucket.appspot.com/build/8774294281621175969
[20:58:41.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:2:0) after 2m57s
[20:58:42.741] Received PubSub notification, asking Buildbucket for the build status
[20:58:42.774] Build status: SCHEDULED
[21:01:39.571] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:2:0)
[21:01:39.600] Build status: SCHEDULED
[21:01:39.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:4:0) after 5m49s
[21:07:28.684] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:4:0)
[21:07:28.705] Build status: SCHEDULED
[21:07:28.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:5:0) after 7m42s
[21:09:52.435] Received PubSub notification, asking Buildbucket for the build status
[21:09:52.466] Build status: STARTED
[21:15:10.960] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:5:0)
[21:15:10.994] Build status: STARTED
[21:15:10.994] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:7:0) after 9m25s
[21:24:36.166] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:7:0)
[21:24:36.205] Build status: STARTED
[21:24:36.205] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:8:0) after 4m33s
[21:29:09.349] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:8:0)
[21:29:09.366] Build status: STARTED
[21:29:09.366] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:9:0) after 5m34s
[21:34:43.783] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:9:0)
[21:34:43.803] Build status: STARTED
[21:34:43.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:10:0) after 8m2s
[21:42:46.051] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:10:0)
[21:42:46.071] Build status: STARTED
[21:42:46.071] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:11:0) after 4m49s
[21:47:35.093] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:11:0)
[21:47:35.124] Build status: STARTED
[21:47:35.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:12:0) after 3m20s
[21:50:55.361] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:12:0)
[21:50:55.387] Build status: STARTED
[21:50:55.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:13:0) after 1m45s
[21:52:40.519] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:13:0)
[21:52:40.537] Build status: STARTED
[21:52:40.537] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:14:0) after 5m20s
[21:58:00.556] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:14:0)
[21:58:00.574] Build status: STARTED
[21:58:00.574] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:15:0) after 1m53s
[21:59:53.595] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:15:0)
[21:59:53.614] Build status: STARTED
[21:59:53.614] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:16:0) after 7m6s
[22:06:59.816] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:16:0)
[22:06:59.892] Build status: STARTED
[22:06:59.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:17:0) after 1m30s
[22:08:30.286] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:17:0)
[22:08:30.314] Build status: STARTED
[22:08:30.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939724343477654352:18:0) after 7m6s
[22:14:57.564] Received PubSub notification, asking Buildbucket for the build status
[22:14:57.580] Build:
{
"id": "8774294281621175969",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-07-28T20:58:41.235987377Z",
"startTime": "2023-07-28T21:09:51.506539Z",
"endTime": "2023-07-28T22:14:57.171576799Z",
"updateTime": "2023-07-28T22:14:57.171576799Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1bf6f55911ca6427789093b1df8a63d7e72dac51",
"ref": "refs/heads/main"
}
}
}
[22:14:57.580] Invocation finished in 1h16m17.498839076s with status SUCCEEDED