[20:09:46.650] New invocation is queued and will start shortly
[20:09:49.116] Starting the invocation (attempt 1)
[20:09:49.210] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/1780e16a6745818d25683dc2cf90f4f2c1985664"
[20:09:49.210] Popped gitiles commit info from properties and tags
[20:09:49.210] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:09:49.212] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:09:49.212] Buildbucket request:
{
"requestId": "8870873724954177296",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870873724954177296",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9b3999b1ffd7d6ac976e6f9f7c39f88a5e457e1c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9b3999b1ffd7d6ac976e6f9f7c39f88a5e457e1c",
"title": "9b3999b1ffd7d6ac976e6f9f7c39f88a5e457e1c",
"url": "https://llvm.googlesource.com/llvm-project/+/9b3999b1ffd7d6ac976e6f9f7c39f88a5e457e1c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5088795994aa36482dbbc766b2f4e7ad97dbd8d5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5088795994aa36482dbbc766b2f4e7ad97dbd8d5",
"title": "5088795994aa36482dbbc766b2f4e7ad97dbd8d5",
"url": "https://llvm.googlesource.com/llvm-project/+/5088795994aa36482dbbc766b2f4e7ad97dbd8d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5953e07c8cae6e60bfa7bdafecd7b398cfe9ba4a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5953e07c8cae6e60bfa7bdafecd7b398cfe9ba4a",
"title": "5953e07c8cae6e60bfa7bdafecd7b398cfe9ba4a",
"url": "https://llvm.googlesource.com/llvm-project/+/5953e07c8cae6e60bfa7bdafecd7b398cfe9ba4a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c81cc9f55b8d21d9421cdb00755b241f9852eca0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c81cc9f55b8d21d9421cdb00755b241f9852eca0",
"title": "c81cc9f55b8d21d9421cdb00755b241f9852eca0",
"url": "https://llvm.googlesource.com/llvm-project/+/c81cc9f55b8d21d9421cdb00755b241f9852eca0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4a4b810c27bf293cf5e32d2801e598c0922d0675"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4a4b810c27bf293cf5e32d2801e598c0922d0675",
"title": "4a4b810c27bf293cf5e32d2801e598c0922d0675",
"url": "https://llvm.googlesource.com/llvm-project/+/4a4b810c27bf293cf5e32d2801e598c0922d0675"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a1eeb5902723116cf23e7d52380d1acca5b5530d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a1eeb5902723116cf23e7d52380d1acca5b5530d",
"title": "a1eeb5902723116cf23e7d52380d1acca5b5530d",
"url": "https://llvm.googlesource.com/llvm-project/+/a1eeb5902723116cf23e7d52380d1acca5b5530d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "914374624f19eb6c43bc272a4f600975f80e011a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@914374624f19eb6c43bc272a4f600975f80e011a",
"title": "914374624f19eb6c43bc272a4f600975f80e011a",
"url": "https://llvm.googlesource.com/llvm-project/+/914374624f19eb6c43bc272a4f600975f80e011a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "99bfe6e58b7dcec89c34285e78ac0a40577fb0c7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@99bfe6e58b7dcec89c34285e78ac0a40577fb0c7",
"title": "99bfe6e58b7dcec89c34285e78ac0a40577fb0c7",
"url": "https://llvm.googlesource.com/llvm-project/+/99bfe6e58b7dcec89c34285e78ac0a40577fb0c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9b0b2385b2b5f638af2f94181950b0f0d4819123"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9b0b2385b2b5f638af2f94181950b0f0d4819123",
"title": "9b0b2385b2b5f638af2f94181950b0f0d4819123",
"url": "https://llvm.googlesource.com/llvm-project/+/9b0b2385b2b5f638af2f94181950b0f0d4819123"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e64c9d1c2fc2a805abc5701e386299b47a72aeea"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e64c9d1c2fc2a805abc5701e386299b47a72aeea",
"title": "e64c9d1c2fc2a805abc5701e386299b47a72aeea",
"url": "https://llvm.googlesource.com/llvm-project/+/e64c9d1c2fc2a805abc5701e386299b47a72aeea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2bfbae99b93ab17cf6a53d826301ba51c52b6129"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2bfbae99b93ab17cf6a53d826301ba51c52b6129",
"title": "2bfbae99b93ab17cf6a53d826301ba51c52b6129",
"url": "https://llvm.googlesource.com/llvm-project/+/2bfbae99b93ab17cf6a53d826301ba51c52b6129"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e3b0e92912486d9135eeac06716bf74a18f07ea9"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e3b0e92912486d9135eeac06716bf74a18f07ea9",
"title": "e3b0e92912486d9135eeac06716bf74a18f07ea9",
"url": "https://llvm.googlesource.com/llvm-project/+/e3b0e92912486d9135eeac06716bf74a18f07ea9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "8344a53c0decd9168a659b837919615241b43a2e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@8344a53c0decd9168a659b837919615241b43a2e",
"title": "8344a53c0decd9168a659b837919615241b43a2e",
"url": "https://llvm.googlesource.com/llvm-project/+/8344a53c0decd9168a659b837919615241b43a2e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2ec71d93ad888d9523425930ef8c35fe8f0b2485"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2ec71d93ad888d9523425930ef8c35fe8f0b2485",
"title": "2ec71d93ad888d9523425930ef8c35fe8f0b2485",
"url": "https://llvm.googlesource.com/llvm-project/+/2ec71d93ad888d9523425930ef8c35fe8f0b2485"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "17a49c405b63ccb1925a838710547cee1821cf93"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@17a49c405b63ccb1925a838710547cee1821cf93",
"title": "17a49c405b63ccb1925a838710547cee1821cf93",
"url": "https://llvm.googlesource.com/llvm-project/+/17a49c405b63ccb1925a838710547cee1821cf93"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9dfb04d225769f01e2ee10f71a8052d047a55cb1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9dfb04d225769f01e2ee10f71a8052d047a55cb1",
"title": "9dfb04d225769f01e2ee10f71a8052d047a55cb1",
"url": "https://llvm.googlesource.com/llvm-project/+/9dfb04d225769f01e2ee10f71a8052d047a55cb1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b90f4ff3020c6fe018ec22b22a7faa5541ed5372"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b90f4ff3020c6fe018ec22b22a7faa5541ed5372",
"title": "b90f4ff3020c6fe018ec22b22a7faa5541ed5372",
"url": "https://llvm.googlesource.com/llvm-project/+/b90f4ff3020c6fe018ec22b22a7faa5541ed5372"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "aa14b3eed916d40f89b041fdcee7f67832654206"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@aa14b3eed916d40f89b041fdcee7f67832654206",
"title": "aa14b3eed916d40f89b041fdcee7f67832654206",
"url": "https://llvm.googlesource.com/llvm-project/+/aa14b3eed916d40f89b041fdcee7f67832654206"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1780e16a6745818d25683dc2cf90f4f2c1985664"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1780e16a6745818d25683dc2cf90f4f2c1985664",
"title": "1780e16a6745818d25683dc2cf90f4f2c1985664",
"url": "https://llvm.googlesource.com/llvm-project/+/1780e16a6745818d25683dc2cf90f4f2c1985664"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1780e16a6745818d25683dc2cf90f4f2c1985664",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870873724954177296"
},
{
"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"
}
}
[20:09:50.050] Scheduled build:
{
"id": "8705443661453200529",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-26T20:09:49.358053717Z",
"updateTime": "2025-08-26T20:09:49.358053717Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1780e16a6745818d25683dc2cf90f4f2c1985664",
"ref": "refs/heads/main"
}
}
}
[20:09:50.050] Task URL: https://cr-buildbucket.appspot.com/build/8705443661453200529
[20:09:50.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:2:0) after 9m56s
[20:09:51.046] Received PubSub notification, asking Buildbucket for the build status
[20:09:51.092] Build status: SCHEDULED
[20:19:46.076] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:2:0)
[20:19:46.157] Build status: SCHEDULED
[20:19:46.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:4:0) after 5m23s
[20:20:41.368] Received PubSub notification, asking Buildbucket for the build status
[20:20:41.392] Build status: STARTED
[20:25:09.192] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:4:0)
[20:25:09.216] Build status: STARTED
[20:25:09.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:6:0) after 1m16s
[20:26:25.234] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:6:0)
[20:26:25.248] Build status: STARTED
[20:26:25.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:7:0) after 3m19s
[20:29:44.264] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:7:0)
[20:29:44.293] Build status: STARTED
[20:29:44.293] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:8:0) after 2m24s
[20:32:08.265] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:8:0)
[20:32:08.286] Build status: STARTED
[20:32:08.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:9:0) after 1m22s
[20:33:30.301] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:9:0)
[20:33:30.323] Build status: STARTED
[20:33:30.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:10:0) after 7m23s
[20:40:53.445] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:10:0)
[20:40:53.464] Build status: STARTED
[20:40:53.464] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:11:0) after 8m5s
[20:48:58.492] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:11:0)
[20:48:58.510] Build status: STARTED
[20:48:58.510] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:12:0) after 7m23s
[20:56:21.525] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:12:0)
[20:56:21.579] Build status: STARTED
[20:56:21.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:13:0) after 3m10s
[20:59:31.639] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:13:0)
[20:59:31.656] Build status: STARTED
[20:59:31.656] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:14:0) after 1m17s
[21:00:48.674] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:14:0)
[21:00:48.692] Build status: STARTED
[21:00:48.692] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:15:0) after 3m24s
[21:04:12.706] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:15:0)
[21:04:12.751] Build status: STARTED
[21:04:12.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:16:0) after 2m22s
[21:06:34.816] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:16:0)
[21:06:34.854] Build status: STARTED
[21:06:34.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:17:0) after 2m31s
[21:09:05.873] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:17:0)
[21:09:05.892] Build status: STARTED
[21:09:05.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:18:0) after 8m53s
[21:17:58.998] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:18:0)
[21:17:59.016] Build status: STARTED
[21:17:59.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:19:0) after 5m32s
[21:23:31.067] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:19:0)
[21:23:31.085] Build status: STARTED
[21:23:31.085] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:20:0) after 1m23s
[21:24:54.077] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:20:0)
[21:24:54.131] Build status: STARTED
[21:24:54.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:21:0) after 2m55s
[21:27:49.131] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:21:0)
[21:27:49.151] Build status: STARTED
[21:27:49.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:22:0) after 4m14s
[21:32:03.217] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:22:0)
[21:32:03.250] Build status: STARTED
[21:32:03.250] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:23:0) after 1m57s
[21:34:00.255] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:23:0)
[21:34:00.273] Build status: STARTED
[21:34:00.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:24:0) after 1m34s
[21:35:34.385] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:24:0)
[21:35:34.404] Build status: STARTED
[21:35:34.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:25:0) after 2m25s
[21:37:59.425] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:25:0)
[21:37:59.455] Build status: STARTED
[21:37:59.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:26:0) after 8m27s
[21:46:26.578] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:26:0)
[21:46:26.595] Build status: STARTED
[21:46:26.595] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:27:0) after 5m59s
[21:52:25.662] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:27:0)
[21:52:25.688] Build status: STARTED
[21:52:25.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8870873724954177296:28:0) after 7m17s
[21:56:50.021] Received PubSub notification, asking Buildbucket for the build status
[21:56:50.040] Build:
{
"id": "8705443661453200529",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-08-26T20:09:49.358053717Z",
"startTime": "2025-08-26T20:20:41.113049178Z",
"endTime": "2025-08-26T21:56:49.590523487Z",
"updateTime": "2025-08-26T21:56:49.590523487Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "1780e16a6745818d25683dc2cf90f4f2c1985664",
"ref": "refs/heads/main"
}
}
}
[21:56:50.040] Invocation finished in 1h47m3.40383945s with status SUCCEEDED