[10:25:10.210] New invocation is queued and will start shortly
[10:25:11.336] Starting the invocation (attempt 1)
[10:25:11.371] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/f378e52ed3c6f8da4973f97f1ef043c2eb0da721"
[10:25:11.371] Popped gitiles commit info from properties and tags
[10:25:11.371] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:25:11.371] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:25:11.372] Buildbucket request:
{
"requestId": "8888123928874754368",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8888123928874754368",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "94585dc59de23c52274fc542df7b821c7e6bb326"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@94585dc59de23c52274fc542df7b821c7e6bb326",
"title": "94585dc59de23c52274fc542df7b821c7e6bb326",
"url": "https://llvm.googlesource.com/llvm-project/+/94585dc59de23c52274fc542df7b821c7e6bb326"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "948a8477c6a966ee8509400d2857706e933f4149"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@948a8477c6a966ee8509400d2857706e933f4149",
"title": "948a8477c6a966ee8509400d2857706e933f4149",
"url": "https://llvm.googlesource.com/llvm-project/+/948a8477c6a966ee8509400d2857706e933f4149"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ea7897a617b897f87f148db48cda9fcc7c1c53dc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ea7897a617b897f87f148db48cda9fcc7c1c53dc",
"title": "ea7897a617b897f87f148db48cda9fcc7c1c53dc",
"url": "https://llvm.googlesource.com/llvm-project/+/ea7897a617b897f87f148db48cda9fcc7c1c53dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e0545b5c6d54dcf3e3ef84cbf9695bb1aecd87db"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e0545b5c6d54dcf3e3ef84cbf9695bb1aecd87db",
"title": "e0545b5c6d54dcf3e3ef84cbf9695bb1aecd87db",
"url": "https://llvm.googlesource.com/llvm-project/+/e0545b5c6d54dcf3e3ef84cbf9695bb1aecd87db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b9c6d3ed26789c33fc7f959198e4459ec4e1d3ac"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b9c6d3ed26789c33fc7f959198e4459ec4e1d3ac",
"title": "b9c6d3ed26789c33fc7f959198e4459ec4e1d3ac",
"url": "https://llvm.googlesource.com/llvm-project/+/b9c6d3ed26789c33fc7f959198e4459ec4e1d3ac"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ff4e21fccc439085f6381076a2ac7d9fa371ab29"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ff4e21fccc439085f6381076a2ac7d9fa371ab29",
"title": "ff4e21fccc439085f6381076a2ac7d9fa371ab29",
"url": "https://llvm.googlesource.com/llvm-project/+/ff4e21fccc439085f6381076a2ac7d9fa371ab29"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "fb14638817004dc96c9401d7f704d7e5cd0ef3fc"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@fb14638817004dc96c9401d7f704d7e5cd0ef3fc",
"title": "fb14638817004dc96c9401d7f704d7e5cd0ef3fc",
"url": "https://llvm.googlesource.com/llvm-project/+/fb14638817004dc96c9401d7f704d7e5cd0ef3fc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d49776634e3c4bd62649e8e0fc0ff44951413c69"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d49776634e3c4bd62649e8e0fc0ff44951413c69",
"title": "d49776634e3c4bd62649e8e0fc0ff44951413c69",
"url": "https://llvm.googlesource.com/llvm-project/+/d49776634e3c4bd62649e8e0fc0ff44951413c69"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "82dc2d403066a84ef0051b06f1d179e00331f319"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@82dc2d403066a84ef0051b06f1d179e00331f319",
"title": "82dc2d403066a84ef0051b06f1d179e00331f319",
"url": "https://llvm.googlesource.com/llvm-project/+/82dc2d403066a84ef0051b06f1d179e00331f319"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "262e4c19878175780c88da867e88fc4e202d4788"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@262e4c19878175780c88da867e88fc4e202d4788",
"title": "262e4c19878175780c88da867e88fc4e202d4788",
"url": "https://llvm.googlesource.com/llvm-project/+/262e4c19878175780c88da867e88fc4e202d4788"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e0e67a62076ad56f48c64a7cd2ebf5754b8326b7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e0e67a62076ad56f48c64a7cd2ebf5754b8326b7",
"title": "e0e67a62076ad56f48c64a7cd2ebf5754b8326b7",
"url": "https://llvm.googlesource.com/llvm-project/+/e0e67a62076ad56f48c64a7cd2ebf5754b8326b7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c1a229252617ed58f943bf3f4698bd8204ee0f04"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c1a229252617ed58f943bf3f4698bd8204ee0f04",
"title": "c1a229252617ed58f943bf3f4698bd8204ee0f04",
"url": "https://llvm.googlesource.com/llvm-project/+/c1a229252617ed58f943bf3f4698bd8204ee0f04"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "501c77da6024959438c92f33bd997fe6f39e6b6c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@501c77da6024959438c92f33bd997fe6f39e6b6c",
"title": "501c77da6024959438c92f33bd997fe6f39e6b6c",
"url": "https://llvm.googlesource.com/llvm-project/+/501c77da6024959438c92f33bd997fe6f39e6b6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f378e52ed3c6f8da4973f97f1ef043c2eb0da721"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f378e52ed3c6f8da4973f97f1ef043c2eb0da721",
"title": "f378e52ed3c6f8da4973f97f1ef043c2eb0da721",
"url": "https://llvm.googlesource.com/llvm-project/+/f378e52ed3c6f8da4973f97f1ef043c2eb0da721"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "f378e52ed3c6f8da4973f97f1ef043c2eb0da721",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8888123928874754368"
},
{
"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"
}
}
[10:25:11.752] Scheduled build:
{
"id": "8722693866911785793",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-17T10:25:11.459926257Z",
"updateTime": "2025-02-17T10:25:11.459926257Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "f378e52ed3c6f8da4973f97f1ef043c2eb0da721",
"ref": "refs/heads/main"
}
}
}
[10:25:11.752] Task URL: https://cr-buildbucket.appspot.com/build/8722693866911785793
[10:25:11.752] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:2:0) after 3m53s
[10:25:11.964] Received PubSub notification, asking Buildbucket for the build status
[10:25:12.014] Build status: SCHEDULED
[10:29:05.206] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:2:0)
[10:29:05.228] Build status: SCHEDULED
[10:29:05.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:4:0) after 4m19s
[10:33:24.345] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:4:0)
[10:33:24.370] Build status: SCHEDULED
[10:33:24.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:5:0) after 6m40s
[10:40:04.582] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:5:0)
[10:40:04.602] Build status: SCHEDULED
[10:40:04.602] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:6:0) after 4m35s
[10:44:39.658] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:6:0)
[10:44:39.680] Build status: SCHEDULED
[10:44:39.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:7:0) after 8m5s
[10:52:44.703] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:7:0)
[10:52:44.725] Build status: SCHEDULED
[10:52:44.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:8:0) after 7m44s
[11:00:28.846] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:8:0)
[11:00:28.865] Build status: SCHEDULED
[11:00:28.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:9:0) after 6m4s
[11:01:23.952] Received PubSub notification, asking Buildbucket for the build status
[11:01:23.979] Build status: STARTED
[11:06:32.898] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:9:0)
[11:06:32.921] Build status: STARTED
[11:06:32.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:11:0) after 4m4s
[11:10:37.047] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:11:0)
[11:10:37.151] Build status: STARTED
[11:10:37.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:12:0) after 4m51s
[11:15:28.199] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:12:0)
[11:15:28.219] Build status: STARTED
[11:15:28.219] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:13:0) after 6m19s
[11:21:47.243] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:13:0)
[11:21:47.271] Build status: STARTED
[11:21:47.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:14:0) after 7m46s
[11:29:33.423] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:14:0)
[11:29:33.443] Build status: STARTED
[11:29:33.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:15:0) after 6m25s
[11:35:58.601] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:15:0)
[11:35:58.630] Build status: STARTED
[11:35:58.630] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:16:0) after 9m9s
[11:45:07.652] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:16:0)
[11:45:07.673] Build status: STARTED
[11:45:07.673] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:17:0) after 1m24s
[11:46:31.789] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:17:0)
[11:46:31.812] Build status: STARTED
[11:46:31.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:18:0) after 3m8s
[11:49:39.944] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:18:0)
[11:49:39.965] Build status: STARTED
[11:49:39.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:19:0) after 7m24s
[11:57:03.985] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:19:0)
[11:57:04.044] Build status: STARTED
[11:57:04.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:20:0) after 2m59s
[12:00:03.283] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:20:0)
[12:00:03.307] Build status: STARTED
[12:00:03.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:21:0) after 6m5s
[12:06:08.376] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:21:0)
[12:06:08.391] Build status: STARTED
[12:06:08.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:22:0) after 3m6s
[12:09:14.382] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:22:0)
[12:09:14.404] Build status: STARTED
[12:09:14.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:23:0) after 3m25s
[12:12:39.946] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:23:0)
[12:12:39.967] Build status: STARTED
[12:12:39.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:24:0) after 5m0s
[12:17:40.115] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:24:0)
[12:17:40.138] Build status: STARTED
[12:17:40.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:25:0) after 7m47s
[12:25:27.238] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:25:0)
[12:25:27.277] Build status: STARTED
[12:25:27.277] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:26:0) after 2m34s
[12:28:01.399] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:26:0)
[12:28:01.418] Build status: STARTED
[12:28:01.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8888123928874754368:27:0) after 6m32s
[12:31:54.114] Received PubSub notification, asking Buildbucket for the build status
[12:31:54.142] Build:
{
"id": "8722693866911785793",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-02-17T10:25:11.459926257Z",
"startTime": "2025-02-17T11:01:23.771896962Z",
"endTime": "2025-02-17T12:31:45.087432878Z",
"updateTime": "2025-02-17T12:31:45.087432878Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "f378e52ed3c6f8da4973f97f1ef043c2eb0da721",
"ref": "refs/heads/main"
}
}
}
[12:31:54.142] Invocation finished in 2h6m43.938160697s with status SUCCEEDED