[16:02:40.897] New invocation is queued and will start shortly
[16:02:41.957] Starting the invocation (attempt 1)
[16:02:41.992] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/29441e4f5fa5f5c7709f7cf180815ba97f611297"
[16:02:41.992] Popped gitiles commit info from properties and tags
[16:02:41.992] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:02:41.994] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:02:41.994] Buildbucket request:
{
"requestId": "8889824036857749328",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-arm64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889824036857749328",
"job": "fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2e43f3922377d75966cc543f5b1fb01332d25d76"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2e43f3922377d75966cc543f5b1fb01332d25d76",
"title": "2e43f3922377d75966cc543f5b1fb01332d25d76",
"url": "https://llvm.googlesource.com/llvm-project/+/2e43f3922377d75966cc543f5b1fb01332d25d76"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5a4945fa4d515b3209a5e181621bf828e678769f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5a4945fa4d515b3209a5e181621bf828e678769f",
"title": "5a4945fa4d515b3209a5e181621bf828e678769f",
"url": "https://llvm.googlesource.com/llvm-project/+/5a4945fa4d515b3209a5e181621bf828e678769f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "25ae1a266d50f24a8fffc57152d7f3c3fcb65517"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@25ae1a266d50f24a8fffc57152d7f3c3fcb65517",
"title": "25ae1a266d50f24a8fffc57152d7f3c3fcb65517",
"url": "https://llvm.googlesource.com/llvm-project/+/25ae1a266d50f24a8fffc57152d7f3c3fcb65517"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e9c2e0acd747b7b5260cf022233fa9b5336d2d30"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e9c2e0acd747b7b5260cf022233fa9b5336d2d30",
"title": "e9c2e0acd747b7b5260cf022233fa9b5336d2d30",
"url": "https://llvm.googlesource.com/llvm-project/+/e9c2e0acd747b7b5260cf022233fa9b5336d2d30"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "39a72be5e59c9a80570c1085a769593e2f26f054"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@39a72be5e59c9a80570c1085a769593e2f26f054",
"title": "39a72be5e59c9a80570c1085a769593e2f26f054",
"url": "https://llvm.googlesource.com/llvm-project/+/39a72be5e59c9a80570c1085a769593e2f26f054"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f226cabbb1b9737676536bc4417336bef4808992"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f226cabbb1b9737676536bc4417336bef4808992",
"title": "f226cabbb1b9737676536bc4417336bef4808992",
"url": "https://llvm.googlesource.com/llvm-project/+/f226cabbb1b9737676536bc4417336bef4808992"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a01097faca35a9a8927c8b0c514bc35dcebec00f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a01097faca35a9a8927c8b0c514bc35dcebec00f",
"title": "a01097faca35a9a8927c8b0c514bc35dcebec00f",
"url": "https://llvm.googlesource.com/llvm-project/+/a01097faca35a9a8927c8b0c514bc35dcebec00f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "bfabd5be5359f482af462b587b761f7e07cc4075"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@bfabd5be5359f482af462b587b761f7e07cc4075",
"title": "bfabd5be5359f482af462b587b761f7e07cc4075",
"url": "https://llvm.googlesource.com/llvm-project/+/bfabd5be5359f482af462b587b761f7e07cc4075"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "540402c05badc59529b06e31c51c3f2e093a83b2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@540402c05badc59529b06e31c51c3f2e093a83b2",
"title": "540402c05badc59529b06e31c51c3f2e093a83b2",
"url": "https://llvm.googlesource.com/llvm-project/+/540402c05badc59529b06e31c51c3f2e093a83b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "349eab186986e56d3e314193733a0443f6575850"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@349eab186986e56d3e314193733a0443f6575850",
"title": "349eab186986e56d3e314193733a0443f6575850",
"url": "https://llvm.googlesource.com/llvm-project/+/349eab186986e56d3e314193733a0443f6575850"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0c63ec5347d2c86ea00c77437dfaf65a360fdafd"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0c63ec5347d2c86ea00c77437dfaf65a360fdafd",
"title": "0c63ec5347d2c86ea00c77437dfaf65a360fdafd",
"url": "https://llvm.googlesource.com/llvm-project/+/0c63ec5347d2c86ea00c77437dfaf65a360fdafd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a34a087fc59779c53512eda094e1ca914a4526f2"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a34a087fc59779c53512eda094e1ca914a4526f2",
"title": "a34a087fc59779c53512eda094e1ca914a4526f2",
"url": "https://llvm.googlesource.com/llvm-project/+/a34a087fc59779c53512eda094e1ca914a4526f2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c583df46d404507f62c605b6f96cde22dcd9e948"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c583df46d404507f62c605b6f96cde22dcd9e948",
"title": "c583df46d404507f62c605b6f96cde22dcd9e948",
"url": "https://llvm.googlesource.com/llvm-project/+/c583df46d404507f62c605b6f96cde22dcd9e948"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7ab8f286b8e43a98dc5c0404f80d719c49446875"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7ab8f286b8e43a98dc5c0404f80d719c49446875",
"title": "7ab8f286b8e43a98dc5c0404f80d719c49446875",
"url": "https://llvm.googlesource.com/llvm-project/+/7ab8f286b8e43a98dc5c0404f80d719c49446875"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8e4c5cb0063e1c73a3f93073f5f85c8ec598613f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8e4c5cb0063e1c73a3f93073f5f85c8ec598613f",
"title": "8e4c5cb0063e1c73a3f93073f5f85c8ec598613f",
"url": "https://llvm.googlesource.com/llvm-project/+/8e4c5cb0063e1c73a3f93073f5f85c8ec598613f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8baa0d9d545f9daf0d82596cb90f35456efb1153"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8baa0d9d545f9daf0d82596cb90f35456efb1153",
"title": "8baa0d9d545f9daf0d82596cb90f35456efb1153",
"url": "https://llvm.googlesource.com/llvm-project/+/8baa0d9d545f9daf0d82596cb90f35456efb1153"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3c3c850a45c8f1ea1e9d6aa514e2b6076d1a4534"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3c3c850a45c8f1ea1e9d6aa514e2b6076d1a4534",
"title": "3c3c850a45c8f1ea1e9d6aa514e2b6076d1a4534",
"url": "https://llvm.googlesource.com/llvm-project/+/3c3c850a45c8f1ea1e9d6aa514e2b6076d1a4534"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "29441e4f5fa5f5c7709f7cf180815ba97f611297"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@29441e4f5fa5f5c7709f7cf180815ba97f611297",
"title": "29441e4f5fa5f5c7709f7cf180815ba97f611297",
"url": "https://llvm.googlesource.com/llvm-project/+/29441e4f5fa5f5c7709f7cf180815ba97f611297"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "29441e4f5fa5f5c7709f7cf180815ba97f611297",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889824036857749328"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:02:42.439] Scheduled build:
{
"id": "8724393974977258721",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-arm64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-29T16:02:42.062680112Z",
"updateTime": "2025-01-29T16:02:42.062680112Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "29441e4f5fa5f5c7709f7cf180815ba97f611297",
"ref": "refs/heads/main"
}
},
"canary": true
}
[16:02:42.439] Task URL: https://cr-buildbucket.appspot.com/build/8724393974977258721
[16:02:42.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:2:0) after 2m43s
[16:02:43.420] Received PubSub notification, asking Buildbucket for the build status
[16:02:43.443] Build status: SCHEDULED
[16:02:46.161] Received PubSub notification, asking Buildbucket for the build status
[16:02:46.188] Build status: STARTED
[16:05:25.528] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:2:0)
[16:05:25.586] Build status: STARTED
[16:05:25.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:5:0) after 9m59s
[16:15:24.602] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:5:0)
[16:15:24.665] Build status: STARTED
[16:15:24.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:6:0) after 7m28s
[16:22:52.759] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:6:0)
[16:22:52.791] Build status: STARTED
[16:22:52.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:7:0) after 6m19s
[16:29:11.779] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:7:0)
[16:29:11.797] Build status: STARTED
[16:29:11.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:8:0) after 9m9s
[16:38:20.815] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:8:0)
[16:38:20.833] Build status: STARTED
[16:38:20.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:9:0) after 4m45s
[16:43:05.830] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:9:0)
[16:43:05.857] Build status: STARTED
[16:43:05.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:10:0) after 4m10s
[16:47:15.894] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:10:0)
[16:47:15.944] Build status: STARTED
[16:47:15.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:11:0) after 4m5s
[16:51:21.037] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:11:0)
[16:51:21.060] Build status: STARTED
[16:51:21.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:12:0) after 5m0s
[16:56:21.039] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:12:0)
[16:56:21.118] Build status: STARTED
[16:56:21.118] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:13:0) after 4m32s
[17:00:53.145] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:13:0)
[17:00:53.172] Build status: STARTED
[17:00:53.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:14:0) after 3m53s
[17:04:46.174] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:14:0)
[17:04:46.203] Build status: STARTED
[17:04:46.203] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:15:0) after 3m37s
[17:08:23.209] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:15:0)
[17:08:23.231] Build status: STARTED
[17:08:23.231] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:16:0) after 7m35s
[17:15:58.259] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:16:0)
[17:15:58.283] Build status: STARTED
[17:15:58.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:17:0) after 9m5s
[17:25:03.350] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:17:0)
[17:25:03.380] Build status: STARTED
[17:25:03.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:18:0) after 2m8s
[17:27:11.451] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:18:0)
[17:27:11.482] Build status: STARTED
[17:27:11.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:19:0) after 1m41s
[17:28:52.550] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:19:0)
[17:28:52.569] Build status: STARTED
[17:28:52.569] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:20:0) after 2m38s
[17:31:30.648] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:20:0)
[17:31:30.665] Build status: STARTED
[17:31:30.665] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:21:0) after 8m13s
[17:39:43.687] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:21:0)
[17:39:43.704] Build status: STARTED
[17:39:43.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:22:0) after 2m56s
[17:42:39.779] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:22:0)
[17:42:39.803] Build status: STARTED
[17:42:39.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-arm64-40be1bb5:8889824036857749328:23:0) after 9m32s
[17:45:07.254] Received PubSub notification, asking Buildbucket for the build status
[17:45:07.278] Build:
{
"id": "8724393974977258721",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-arm64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-29T16:02:42.062680112Z",
"startTime": "2025-01-29T16:02:45.791688917Z",
"endTime": "2025-01-29T17:45:06.855663252Z",
"updateTime": "2025-01-29T17:45:06.855663252Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "29441e4f5fa5f5c7709f7cf180815ba97f611297",
"ref": "refs/heads/main"
}
},
"canary": true
}
[17:45:07.278] Invocation finished in 1h42m26.393297702s with status SUCCEEDED