[00:29:14.099] New invocation is queued and will start shortly
[00:29:15.149] Starting the invocation (attempt 1)
[00:29:15.181] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/c09e51ae97795bac9284e345d02532cd2d0bc1e3"
[00:29:15.181] Popped gitiles commit info from properties and tags
[00:29:15.181] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:29:15.181] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:29:15.182] Buildbucket request:
{
"requestId": "8889157988512490320",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889157988512490320",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5f7acf7259ec693cf03d6dcc75d9b0ef1a4b4e81"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5f7acf7259ec693cf03d6dcc75d9b0ef1a4b4e81",
"title": "5f7acf7259ec693cf03d6dcc75d9b0ef1a4b4e81",
"url": "https://llvm.googlesource.com/llvm-project/+/5f7acf7259ec693cf03d6dcc75d9b0ef1a4b4e81"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "fd4c4ed712c14ab80979bcdca121387d83a465ff"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@fd4c4ed712c14ab80979bcdca121387d83a465ff",
"title": "fd4c4ed712c14ab80979bcdca121387d83a465ff",
"url": "https://llvm.googlesource.com/llvm-project/+/fd4c4ed712c14ab80979bcdca121387d83a465ff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "de7be32734dc789ba35602a58f37a14f764ebd58"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@de7be32734dc789ba35602a58f37a14f764ebd58",
"title": "de7be32734dc789ba35602a58f37a14f764ebd58",
"url": "https://llvm.googlesource.com/llvm-project/+/de7be32734dc789ba35602a58f37a14f764ebd58"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0d7ee520d3a9b8997adf8eaaa22b33db9659d94e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0d7ee520d3a9b8997adf8eaaa22b33db9659d94e",
"title": "0d7ee520d3a9b8997adf8eaaa22b33db9659d94e",
"url": "https://llvm.googlesource.com/llvm-project/+/0d7ee520d3a9b8997adf8eaaa22b33db9659d94e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1901f4ac8eb1ddd8df822841a48b818c496aa2ad"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1901f4ac8eb1ddd8df822841a48b818c496aa2ad",
"title": "1901f4ac8eb1ddd8df822841a48b818c496aa2ad",
"url": "https://llvm.googlesource.com/llvm-project/+/1901f4ac8eb1ddd8df822841a48b818c496aa2ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5a1e16f6de26c21cdfae1de05bd075d57029a3e1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5a1e16f6de26c21cdfae1de05bd075d57029a3e1",
"title": "5a1e16f6de26c21cdfae1de05bd075d57029a3e1",
"url": "https://llvm.googlesource.com/llvm-project/+/5a1e16f6de26c21cdfae1de05bd075d57029a3e1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f637708318306d85a4070434badfd7ec5055ceee"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f637708318306d85a4070434badfd7ec5055ceee",
"title": "f637708318306d85a4070434badfd7ec5055ceee",
"url": "https://llvm.googlesource.com/llvm-project/+/f637708318306d85a4070434badfd7ec5055ceee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dd04dc6b29827f170d791edfcc265df9e7455848"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dd04dc6b29827f170d791edfcc265df9e7455848",
"title": "dd04dc6b29827f170d791edfcc265df9e7455848",
"url": "https://llvm.googlesource.com/llvm-project/+/dd04dc6b29827f170d791edfcc265df9e7455848"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2b5cc89b3f77c3d4bd3fb92ec43074cba4e09adb"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2b5cc89b3f77c3d4bd3fb92ec43074cba4e09adb",
"title": "2b5cc89b3f77c3d4bd3fb92ec43074cba4e09adb",
"url": "https://llvm.googlesource.com/llvm-project/+/2b5cc89b3f77c3d4bd3fb92ec43074cba4e09adb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1c4ee0613508a6c0379f9f63a110b0633f78910d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1c4ee0613508a6c0379f9f63a110b0633f78910d",
"title": "1c4ee0613508a6c0379f9f63a110b0633f78910d",
"url": "https://llvm.googlesource.com/llvm-project/+/1c4ee0613508a6c0379f9f63a110b0633f78910d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c552c6c914290f45bf015c4023c42e40a91e253e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c552c6c914290f45bf015c4023c42e40a91e253e",
"title": "c552c6c914290f45bf015c4023c42e40a91e253e",
"url": "https://llvm.googlesource.com/llvm-project/+/c552c6c914290f45bf015c4023c42e40a91e253e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "cd9795c10b70d145ac51d7c22801be9b8c800466"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@cd9795c10b70d145ac51d7c22801be9b8c800466",
"title": "cd9795c10b70d145ac51d7c22801be9b8c800466",
"url": "https://llvm.googlesource.com/llvm-project/+/cd9795c10b70d145ac51d7c22801be9b8c800466"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "aefa30e2301f155d4f4737d6f6c55c66eac58b2d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@aefa30e2301f155d4f4737d6f6c55c66eac58b2d",
"title": "aefa30e2301f155d4f4737d6f6c55c66eac58b2d",
"url": "https://llvm.googlesource.com/llvm-project/+/aefa30e2301f155d4f4737d6f6c55c66eac58b2d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "88f55d16c4c247a9eef326961a1445dee3f2e30c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@88f55d16c4c247a9eef326961a1445dee3f2e30c",
"title": "88f55d16c4c247a9eef326961a1445dee3f2e30c",
"url": "https://llvm.googlesource.com/llvm-project/+/88f55d16c4c247a9eef326961a1445dee3f2e30c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "df1bee03de1cdc1505a4e9dde3ed064eb7db785f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@df1bee03de1cdc1505a4e9dde3ed064eb7db785f",
"title": "df1bee03de1cdc1505a4e9dde3ed064eb7db785f",
"url": "https://llvm.googlesource.com/llvm-project/+/df1bee03de1cdc1505a4e9dde3ed064eb7db785f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e335ca7b8440a61445db7f80efde794f91a28d57"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e335ca7b8440a61445db7f80efde794f91a28d57",
"title": "e335ca7b8440a61445db7f80efde794f91a28d57",
"url": "https://llvm.googlesource.com/llvm-project/+/e335ca7b8440a61445db7f80efde794f91a28d57"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c09e51ae97795bac9284e345d02532cd2d0bc1e3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c09e51ae97795bac9284e345d02532cd2d0bc1e3",
"title": "c09e51ae97795bac9284e345d02532cd2d0bc1e3",
"url": "https://llvm.googlesource.com/llvm-project/+/c09e51ae97795bac9284e345d02532cd2d0bc1e3"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c09e51ae97795bac9284e345d02532cd2d0bc1e3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889157988512490320"
},
{
"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"
}
}
[00:29:15.556] Scheduled build:
{
"id": "8723727926657407937",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-06T00:29:15.238048142Z",
"updateTime": "2025-02-06T00:29:15.238048142Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c09e51ae97795bac9284e345d02532cd2d0bc1e3",
"ref": "refs/heads/main"
}
}
}
[00:29:15.556] Task URL: https://cr-buildbucket.appspot.com/build/8723727926657407937
[00:29:15.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:2:0) after 3m37s
[00:29:16.598] Received PubSub notification, asking Buildbucket for the build status
[00:29:16.621] Build status: SCHEDULED
[00:32:52.598] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:2:0)
[00:32:52.622] Build status: SCHEDULED
[00:32:52.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:4:0) after 8m46s
[00:41:38.665] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:4:0)
[00:41:38.681] Build status: SCHEDULED
[00:41:38.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:5:0) after 2m3s
[00:42:26.323] Received PubSub notification, asking Buildbucket for the build status
[00:42:26.343] Build status: STARTED
[00:43:41.706] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:5:0)
[00:43:41.729] Build status: STARTED
[00:43:41.729] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:7:0) after 3m17s
[00:46:58.753] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:7:0)
[00:46:58.785] Build status: STARTED
[00:46:58.785] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:8:0) after 7m23s
[00:54:21.808] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:8:0)
[00:54:21.829] Build status: STARTED
[00:54:21.829] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:9:0) after 2m46s
[00:57:07.852] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:9:0)
[00:57:07.882] Build status: STARTED
[00:57:07.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:10:0) after 7m46s
[01:04:53.912] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:10:0)
[01:04:53.932] Build status: STARTED
[01:04:53.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:11:0) after 2m15s
[01:07:08.902] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:11:0)
[01:07:08.925] Build status: STARTED
[01:07:08.925] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:12:0) after 1m11s
[01:08:19.956] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:12:0)
[01:08:19.986] Build status: STARTED
[01:08:19.986] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:13:0) after 5m55s
[01:14:15.050] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:13:0)
[01:14:15.080] Build status: STARTED
[01:14:15.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:14:0) after 1m8s
[01:15:23.141] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:14:0)
[01:15:23.158] Build status: STARTED
[01:15:23.158] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:15:0) after 7m35s
[01:22:58.188] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:15:0)
[01:22:58.210] Build status: STARTED
[01:22:58.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:16:0) after 4m49s
[01:27:47.250] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:16:0)
[01:27:47.277] Build status: STARTED
[01:27:47.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:17:0) after 2m15s
[01:30:02.327] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:17:0)
[01:30:02.345] Build status: STARTED
[01:30:02.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:18:0) after 7m39s
[01:37:41.339] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:18:0)
[01:37:41.360] Build status: STARTED
[01:37:41.360] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:19:0) after 6m22s
[01:44:03.365] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:19:0)
[01:44:03.408] Build status: STARTED
[01:44:03.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:20:0) after 3m47s
[01:47:50.447] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:20:0)
[01:47:50.469] Build status: STARTED
[01:47:50.470] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:21:0) after 1m13s
[01:49:03.499] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:21:0)
[01:49:03.518] Build status: STARTED
[01:49:03.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:22:0) after 5m17s
[01:54:20.679] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:22:0)
[01:54:20.716] Build status: STARTED
[01:54:20.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:23:0) after 9m14s
[02:03:34.783] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:23:0)
[02:03:34.860] Build status: STARTED
[02:03:34.860] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:24:0) after 2m24s
[02:05:58.885] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:24:0)
[02:05:58.931] Build status: STARTED
[02:05:58.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:25:0) after 2m21s
[02:08:19.927] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:25:0)
[02:08:19.943] Build status: STARTED
[02:08:19.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:26:0) after 4m25s
[02:12:44.966] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:26:0)
[02:12:44.981] Build status: STARTED
[02:12:44.981] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:27:0) after 5m33s
[02:18:17.997] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:27:0)
[02:18:18.028] Build status: STARTED
[02:18:18.028] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8889157988512490320:28:0) after 9m13s
[02:19:11.250] Received PubSub notification, asking Buildbucket for the build status
[02:19:11.268] Build:
{
"id": "8723727926657407937",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-06T00:29:15.238048142Z",
"startTime": "2025-02-06T00:42:26.122930497Z",
"endTime": "2025-02-06T02:19:10.892613098Z",
"updateTime": "2025-02-06T02:19:10.892613098Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c09e51ae97795bac9284e345d02532cd2d0bc1e3",
"ref": "refs/heads/main"
}
}
}
[02:19:11.268] Invocation finished in 1h49m57.182276516s with status SUCCEEDED