[21:08:59.358] New invocation is queued and will start shortly
[21:09:01.089] Starting the invocation (attempt 1)
[21:09:01.132] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/3dbcd733ad594f0da9a442899bce2758ae7ed76d"
[21:09:01.132] Popped gitiles commit info from properties and tags
[21:09:01.132] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:09:01.132] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:09:01.132] Buildbucket request:
{
"requestId": "8931660564124000400",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8931660564124000400",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "da1736eba6adbe2d305e75e7ec8fefb41b7fde46"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@da1736eba6adbe2d305e75e7ec8fefb41b7fde46",
"title": "da1736eba6adbe2d305e75e7ec8fefb41b7fde46",
"url": "https://llvm.googlesource.com/llvm-project/+/da1736eba6adbe2d305e75e7ec8fefb41b7fde46"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b3795204b68cb0f844001a0200d30e295efe24d5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b3795204b68cb0f844001a0200d30e295efe24d5",
"title": "b3795204b68cb0f844001a0200d30e295efe24d5",
"url": "https://llvm.googlesource.com/llvm-project/+/b3795204b68cb0f844001a0200d30e295efe24d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c2b64dfaa4029489d72fe3e1d6e2a8fd33f4510a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c2b64dfaa4029489d72fe3e1d6e2a8fd33f4510a",
"title": "c2b64dfaa4029489d72fe3e1d6e2a8fd33f4510a",
"url": "https://llvm.googlesource.com/llvm-project/+/c2b64dfaa4029489d72fe3e1d6e2a8fd33f4510a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "77edd9b77317d008b86a2543fbafdc0c3e8a3759"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@77edd9b77317d008b86a2543fbafdc0c3e8a3759",
"title": "77edd9b77317d008b86a2543fbafdc0c3e8a3759",
"url": "https://llvm.googlesource.com/llvm-project/+/77edd9b77317d008b86a2543fbafdc0c3e8a3759"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3c2203ae03ca8a8cf56691d6f03050ccc2420ff6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3c2203ae03ca8a8cf56691d6f03050ccc2420ff6",
"title": "3c2203ae03ca8a8cf56691d6f03050ccc2420ff6",
"url": "https://llvm.googlesource.com/llvm-project/+/3c2203ae03ca8a8cf56691d6f03050ccc2420ff6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0a8f54c3febd06364218a6f5f0810eb72e0265ae"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0a8f54c3febd06364218a6f5f0810eb72e0265ae",
"title": "0a8f54c3febd06364218a6f5f0810eb72e0265ae",
"url": "https://llvm.googlesource.com/llvm-project/+/0a8f54c3febd06364218a6f5f0810eb72e0265ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "29181bd97a24c85bf160ead132bf91027d6bc73c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@29181bd97a24c85bf160ead132bf91027d6bc73c",
"title": "29181bd97a24c85bf160ead132bf91027d6bc73c",
"url": "https://llvm.googlesource.com/llvm-project/+/29181bd97a24c85bf160ead132bf91027d6bc73c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "716c0220f21d974a4c52bd3749c6624f5a3c51d5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@716c0220f21d974a4c52bd3749c6624f5a3c51d5",
"title": "716c0220f21d974a4c52bd3749c6624f5a3c51d5",
"url": "https://llvm.googlesource.com/llvm-project/+/716c0220f21d974a4c52bd3749c6624f5a3c51d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ca8d02d78ade46fca1569741f8f8639eb54538c7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ca8d02d78ade46fca1569741f8f8639eb54538c7",
"title": "ca8d02d78ade46fca1569741f8f8639eb54538c7",
"url": "https://llvm.googlesource.com/llvm-project/+/ca8d02d78ade46fca1569741f8f8639eb54538c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8958f0dca0b57262d187eb4c9721e9df276251ea"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8958f0dca0b57262d187eb4c9721e9df276251ea",
"title": "8958f0dca0b57262d187eb4c9721e9df276251ea",
"url": "https://llvm.googlesource.com/llvm-project/+/8958f0dca0b57262d187eb4c9721e9df276251ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7e83a1af5dc764943d77917f30bf4dbdb7277c35"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7e83a1af5dc764943d77917f30bf4dbdb7277c35",
"title": "7e83a1af5dc764943d77917f30bf4dbdb7277c35",
"url": "https://llvm.googlesource.com/llvm-project/+/7e83a1af5dc764943d77917f30bf4dbdb7277c35"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3dbcd733ad594f0da9a442899bce2758ae7ed76d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3dbcd733ad594f0da9a442899bce2758ae7ed76d",
"title": "3dbcd733ad594f0da9a442899bce2758ae7ed76d",
"url": "https://llvm.googlesource.com/llvm-project/+/3dbcd733ad594f0da9a442899bce2758ae7ed76d"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3dbcd733ad594f0da9a442899bce2758ae7ed76d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8931660564124000400"
},
{
"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"
}
}
[21:09:01.923] Scheduled build:
{
"id": "8766230501451943393",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-25T21:09:01.276762018Z",
"updateTime": "2023-10-25T21:09:01.276762018Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3dbcd733ad594f0da9a442899bce2758ae7ed76d",
"ref": "refs/heads/main"
}
}
}
[21:09:01.923] Task URL: https://cr-buildbucket.appspot.com/build/8766230501451943393
[21:09:01.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:2:0) after 3m50s
[21:09:02.759] Received PubSub notification, asking Buildbucket for the build status
[21:09:02.783] Build status: SCHEDULED
[21:12:51.959] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:2:0)
[21:12:52.075] Build status: SCHEDULED
[21:12:52.075] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:4:0) after 4m20s
[21:17:12.100] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:4:0)
[21:17:12.129] Build status: SCHEDULED
[21:17:12.129] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:5:0) after 8m23s
[21:25:35.249] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:5:0)
[21:25:35.336] Build status: SCHEDULED
[21:25:35.336] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:6:0) after 2m46s
[21:28:21.387] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:6:0)
[21:28:21.407] Build status: SCHEDULED
[21:28:21.407] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:7:0) after 2m1s
[21:30:22.461] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:7:0)
[21:30:22.491] Build status: SCHEDULED
[21:30:22.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:8:0) after 5m55s
[21:36:17.515] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:8:0)
[21:36:17.534] Build status: SCHEDULED
[21:36:17.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:9:0) after 7m52s
[21:44:09.605] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:9:0)
[21:44:09.628] Build status: SCHEDULED
[21:44:09.628] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:10:0) after 2m37s
[21:44:25.172] Received PubSub notification, asking Buildbucket for the build status
[21:44:25.193] Build status: STARTED
[21:46:46.752] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:10:0)
[21:46:46.833] Build status: STARTED
[21:46:46.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:12:0) after 2m52s
[21:49:39.005] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:12:0)
[21:49:39.028] Build status: STARTED
[21:49:39.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:13:0) after 2m40s
[21:52:19.094] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:13:0)
[21:52:19.117] Build status: STARTED
[21:52:19.117] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:14:0) after 7m10s
[21:59:29.124] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:14:0)
[21:59:29.142] Build status: STARTED
[21:59:29.142] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:15:0) after 4m52s
[22:04:21.363] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:15:0)
[22:04:21.386] Build status: STARTED
[22:04:21.386] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:16:0) after 6m43s
[22:11:04.705] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:16:0)
[22:11:04.770] Build status: STARTED
[22:11:04.770] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:17:0) after 6m47s
[22:17:51.818] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:17:0)
[22:17:51.839] Build status: STARTED
[22:17:51.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:18:0) after 8m34s
[22:26:25.862] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:18:0)
[22:26:25.882] Build status: STARTED
[22:26:25.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:19:0) after 9m39s
[22:36:05.029] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:19:0)
[22:36:05.124] Build status: STARTED
[22:36:05.124] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8931660564124000400:20:0) after 5m46s
[22:38:55.158] Received PubSub notification, asking Buildbucket for the build status
[22:38:55.177] Build:
{
"id": "8766230501451943393",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-25T21:09:01.276762018Z",
"startTime": "2023-10-25T21:44:24.610114Z",
"endTime": "2023-10-25T22:38:54.859578075Z",
"updateTime": "2023-10-25T22:38:54.859578075Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "3dbcd733ad594f0da9a442899bce2758ae7ed76d",
"ref": "refs/heads/main"
}
}
}
[22:38:55.177] Invocation finished in 1h29m55.832303444s with status SUCCEEDED