[18:16:08.849] New invocation is queued and will start shortly
[18:16:10.014] Starting the invocation (attempt 1)
[18:16:10.039] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/d896696ab4e09141b8f5e4fb5972da27e58608b9"
[18:16:10.039] Popped gitiles commit info from properties and tags
[18:16:10.039] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:10.039] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:10.040] Buildbucket request:
{
"requestId": "8880484152367296432",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880484152367296432",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dd89af7f5525f139fed54cc376cfd2fc64c631ae"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dd89af7f5525f139fed54cc376cfd2fc64c631ae",
"title": "dd89af7f5525f139fed54cc376cfd2fc64c631ae",
"url": "https://llvm.googlesource.com/llvm-project/+/dd89af7f5525f139fed54cc376cfd2fc64c631ae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "74a588464c133447b2d644cee7910084f4f57065"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@74a588464c133447b2d644cee7910084f4f57065",
"title": "74a588464c133447b2d644cee7910084f4f57065",
"url": "https://llvm.googlesource.com/llvm-project/+/74a588464c133447b2d644cee7910084f4f57065"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "fb9b43a0c56de9b1f36e862565c33e0ad637fb36"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@fb9b43a0c56de9b1f36e862565c33e0ad637fb36",
"title": "fb9b43a0c56de9b1f36e862565c33e0ad637fb36",
"url": "https://llvm.googlesource.com/llvm-project/+/fb9b43a0c56de9b1f36e862565c33e0ad637fb36"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dd3d7cfe2e77f0f33b2952b1c965c87c9ea46eb4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dd3d7cfe2e77f0f33b2952b1c965c87c9ea46eb4",
"title": "dd3d7cfe2e77f0f33b2952b1c965c87c9ea46eb4",
"url": "https://llvm.googlesource.com/llvm-project/+/dd3d7cfe2e77f0f33b2952b1c965c87c9ea46eb4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bfd4af85cc873724cf50825cbcad9f4f3739856c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bfd4af85cc873724cf50825cbcad9f4f3739856c",
"title": "bfd4af85cc873724cf50825cbcad9f4f3739856c",
"url": "https://llvm.googlesource.com/llvm-project/+/bfd4af85cc873724cf50825cbcad9f4f3739856c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "eef4b5a0cdf102e5035d6d4f1aa5f85b2b787e84"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@eef4b5a0cdf102e5035d6d4f1aa5f85b2b787e84",
"title": "eef4b5a0cdf102e5035d6d4f1aa5f85b2b787e84",
"url": "https://llvm.googlesource.com/llvm-project/+/eef4b5a0cdf102e5035d6d4f1aa5f85b2b787e84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "fbd056532fbfe768feaf0bf23aa49f2c282be14b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@fbd056532fbfe768feaf0bf23aa49f2c282be14b",
"title": "fbd056532fbfe768feaf0bf23aa49f2c282be14b",
"url": "https://llvm.googlesource.com/llvm-project/+/fbd056532fbfe768feaf0bf23aa49f2c282be14b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f638f86384691052c30eda074e6d199d5a691223"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f638f86384691052c30eda074e6d199d5a691223",
"title": "f638f86384691052c30eda074e6d199d5a691223",
"url": "https://llvm.googlesource.com/llvm-project/+/f638f86384691052c30eda074e6d199d5a691223"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5e7ad630b09b1c1f7e666f2432ba027a0879981e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5e7ad630b09b1c1f7e666f2432ba027a0879981e",
"title": "5e7ad630b09b1c1f7e666f2432ba027a0879981e",
"url": "https://llvm.googlesource.com/llvm-project/+/5e7ad630b09b1c1f7e666f2432ba027a0879981e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e74877bafebeff40f7213c199c8125bb49818fc1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e74877bafebeff40f7213c199c8125bb49818fc1",
"title": "e74877bafebeff40f7213c199c8125bb49818fc1",
"url": "https://llvm.googlesource.com/llvm-project/+/e74877bafebeff40f7213c199c8125bb49818fc1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6d11b17162a921cd3a66c1d59c6b4b94c9466aed"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6d11b17162a921cd3a66c1d59c6b4b94c9466aed",
"title": "6d11b17162a921cd3a66c1d59c6b4b94c9466aed",
"url": "https://llvm.googlesource.com/llvm-project/+/6d11b17162a921cd3a66c1d59c6b4b94c9466aed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b17f3c63de038eac7f93f56dd099ba8995be7e55"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b17f3c63de038eac7f93f56dd099ba8995be7e55",
"title": "b17f3c63de038eac7f93f56dd099ba8995be7e55",
"url": "https://llvm.googlesource.com/llvm-project/+/b17f3c63de038eac7f93f56dd099ba8995be7e55"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "70ca3f41fa1a16ede0b33e0780c04360b50e4dee"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@70ca3f41fa1a16ede0b33e0780c04360b50e4dee",
"title": "70ca3f41fa1a16ede0b33e0780c04360b50e4dee",
"url": "https://llvm.googlesource.com/llvm-project/+/70ca3f41fa1a16ede0b33e0780c04360b50e4dee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d6c3712f07864040c1a6f28033da02dbc30cad22"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d6c3712f07864040c1a6f28033da02dbc30cad22",
"title": "d6c3712f07864040c1a6f28033da02dbc30cad22",
"url": "https://llvm.googlesource.com/llvm-project/+/d6c3712f07864040c1a6f28033da02dbc30cad22"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c8a0513a1a694078e5ea927faf8249ce77084d80"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c8a0513a1a694078e5ea927faf8249ce77084d80",
"title": "c8a0513a1a694078e5ea927faf8249ce77084d80",
"url": "https://llvm.googlesource.com/llvm-project/+/c8a0513a1a694078e5ea927faf8249ce77084d80"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "40767e95756f944cb13667d400c8d29cd242fdcc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@40767e95756f944cb13667d400c8d29cd242fdcc",
"title": "40767e95756f944cb13667d400c8d29cd242fdcc",
"url": "https://llvm.googlesource.com/llvm-project/+/40767e95756f944cb13667d400c8d29cd242fdcc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d896696ab4e09141b8f5e4fb5972da27e58608b9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d896696ab4e09141b8f5e4fb5972da27e58608b9",
"title": "d896696ab4e09141b8f5e4fb5972da27e58608b9",
"url": "https://llvm.googlesource.com/llvm-project/+/d896696ab4e09141b8f5e4fb5972da27e58608b9"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "d896696ab4e09141b8f5e4fb5972da27e58608b9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880484152367296432"
},
{
"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:16:10.426] Scheduled build:
{
"id": "8715054090405440145",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-05-12T18:16:10.097099205Z",
"updateTime": "2025-05-12T18:16:10.097099205Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "d896696ab4e09141b8f5e4fb5972da27e58608b9",
"ref": "refs/heads/main"
}
}
}
[18:16:10.426] Task URL: https://cr-buildbucket.appspot.com/build/8715054090405440145
[18:16:10.426] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:2:0) after 3m44s
[18:16:11.552] Received PubSub notification, asking Buildbucket for the build status
[18:16:11.604] Build status: SCHEDULED
[18:19:54.565] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:2:0)
[18:19:54.589] Build status: SCHEDULED
[18:19:54.589] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:4:0) after 4m14s
[18:24:08.622] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:4:0)
[18:24:08.688] Build status: SCHEDULED
[18:24:08.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:5:0) after 6m3s
[18:30:11.707] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:5:0)
[18:30:11.745] Build status: SCHEDULED
[18:30:11.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:6:0) after 2m32s
[18:32:43.812] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:6:0)
[18:32:43.836] Build status: SCHEDULED
[18:32:43.836] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:7:0) after 8m48s
[18:41:31.859] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:7:0)
[18:41:31.891] Build status: SCHEDULED
[18:41:31.891] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:8:0) after 3m25s
[18:43:37.229] Received PubSub notification, asking Buildbucket for the build status
[18:43:37.251] Build status: STARTED
[18:44:56.913] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:8:0)
[18:44:56.938] Build status: STARTED
[18:44:56.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:10:0) after 7m38s
[18:52:34.974] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:10:0)
[18:52:35.014] Build status: STARTED
[18:52:35.014] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:11:0) after 9m10s
[19:01:45.140] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:11:0)
[19:01:45.200] Build status: STARTED
[19:01:45.200] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:12:0) after 5m42s
[19:07:27.333] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:12:0)
[19:07:27.351] Build status: STARTED
[19:07:27.351] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:13:0) after 1m22s
[19:08:49.397] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:13:0)
[19:08:49.477] Build status: STARTED
[19:08:49.478] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:14:0) after 3m23s
[19:12:12.579] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:14:0)
[19:12:12.637] Build status: STARTED
[19:12:12.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:15:0) after 7m56s
[19:20:08.673] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:15:0)
[19:20:08.694] Build status: STARTED
[19:20:08.694] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:16:0) after 5m48s
[19:25:56.727] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:16:0)
[19:25:56.755] Build status: STARTED
[19:25:56.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:17:0) after 1m47s
[19:27:43.828] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:17:0)
[19:27:43.854] Build status: STARTED
[19:27:43.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:18:0) after 8m22s
[19:36:05.870] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:18:0)
[19:36:05.888] Build status: STARTED
[19:36:05.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:19:0) after 1m59s
[19:38:04.912] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:19:0)
[19:38:04.931] Build status: STARTED
[19:38:04.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:20:0) after 7m42s
[19:45:47.007] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:20:0)
[19:45:47.023] Build status: STARTED
[19:45:47.023] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:21:0) after 6m4s
[19:51:51.108] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:21:0)
[19:51:51.139] Build status: STARTED
[19:51:51.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:22:0) after 8m44s
[20:00:35.240] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:22:0)
[20:00:35.263] Build status: STARTED
[20:00:35.263] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:23:0) after 6m30s
[20:07:05.396] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:23:0)
[20:07:05.477] Build status: STARTED
[20:07:05.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:24:0) after 3m36s
[20:10:41.535] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:24:0)
[20:10:41.553] Build status: STARTED
[20:10:41.553] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:25:0) after 2m45s
[20:13:26.586] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:25:0)
[20:13:26.634] Build status: STARTED
[20:13:26.634] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8880484152367296432:26:0) after 7m21s
[20:19:00.795] Received PubSub notification, asking Buildbucket for the build status
[20:19:00.822] Build:
{
"id": "8715054090405440145",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-05-12T18:16:10.097099205Z",
"startTime": "2025-05-12T18:43:11.809167115Z",
"endTime": "2025-05-12T20:19:00.261743104Z",
"updateTime": "2025-05-12T20:19:00.261743104Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "d896696ab4e09141b8f5e4fb5972da27e58608b9",
"ref": "refs/heads/main"
}
}
}
[20:19:00.822] Invocation finished in 2h2m51.980745301s with status SUCCEEDED