[14:54:04.855] New invocation is queued and will start shortly
[14:54:05.917] Starting the invocation (attempt 1)
[14:54:05.952] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/515bd40b4ef56b879f5fa80888389d1557d7218b"
[14:54:05.952] Popped gitiles commit info from properties and tags
[14:54:05.952] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:54:05.952] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:54:05.953] Buildbucket request:
{
"requestId": "8937301163230149312",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937301163230149312",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "243d8cdb03142f0d142482f7a44931cc1a0138da"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@243d8cdb03142f0d142482f7a44931cc1a0138da",
"title": "243d8cdb03142f0d142482f7a44931cc1a0138da",
"url": "https://llvm.googlesource.com/llvm-project/+/243d8cdb03142f0d142482f7a44931cc1a0138da"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ff7d2fabe074d95cefb683d4a742eec172bd36d5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ff7d2fabe074d95cefb683d4a742eec172bd36d5",
"title": "ff7d2fabe074d95cefb683d4a742eec172bd36d5",
"url": "https://llvm.googlesource.com/llvm-project/+/ff7d2fabe074d95cefb683d4a742eec172bd36d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3bb32c61b2f1f5d14dd056dd198dc898dce5a44e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3bb32c61b2f1f5d14dd056dd198dc898dce5a44e",
"title": "3bb32c61b2f1f5d14dd056dd198dc898dce5a44e",
"url": "https://llvm.googlesource.com/llvm-project/+/3bb32c61b2f1f5d14dd056dd198dc898dce5a44e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "158f4f30adb4bfd390057742a32934e4344e8fd3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@158f4f30adb4bfd390057742a32934e4344e8fd3",
"title": "158f4f30adb4bfd390057742a32934e4344e8fd3",
"url": "https://llvm.googlesource.com/llvm-project/+/158f4f30adb4bfd390057742a32934e4344e8fd3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3f98cdc815bddd2b69e3298fa104c0305503292e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3f98cdc815bddd2b69e3298fa104c0305503292e",
"title": "3f98cdc815bddd2b69e3298fa104c0305503292e",
"url": "https://llvm.googlesource.com/llvm-project/+/3f98cdc815bddd2b69e3298fa104c0305503292e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5e3ec418ff3b2d15911f743a6ed99dc126d0d8f4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5e3ec418ff3b2d15911f743a6ed99dc126d0d8f4",
"title": "5e3ec418ff3b2d15911f743a6ed99dc126d0d8f4",
"url": "https://llvm.googlesource.com/llvm-project/+/5e3ec418ff3b2d15911f743a6ed99dc126d0d8f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "434bf1608445fd4b529ac4a3a43f1351dc657ab6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@434bf1608445fd4b529ac4a3a43f1351dc657ab6",
"title": "434bf1608445fd4b529ac4a3a43f1351dc657ab6",
"url": "https://llvm.googlesource.com/llvm-project/+/434bf1608445fd4b529ac4a3a43f1351dc657ab6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f4c886b6aaeac0a48f3bdeda3103887599373842"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f4c886b6aaeac0a48f3bdeda3103887599373842",
"title": "f4c886b6aaeac0a48f3bdeda3103887599373842",
"url": "https://llvm.googlesource.com/llvm-project/+/f4c886b6aaeac0a48f3bdeda3103887599373842"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "eea887406498d3a420ed0afbb654915a7e08e2ad"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@eea887406498d3a420ed0afbb654915a7e08e2ad",
"title": "eea887406498d3a420ed0afbb654915a7e08e2ad",
"url": "https://llvm.googlesource.com/llvm-project/+/eea887406498d3a420ed0afbb654915a7e08e2ad"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "515bd40b4ef56b879f5fa80888389d1557d7218b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@515bd40b4ef56b879f5fa80888389d1557d7218b",
"title": "515bd40b4ef56b879f5fa80888389d1557d7218b",
"url": "https://llvm.googlesource.com/llvm-project/+/515bd40b4ef56b879f5fa80888389d1557d7218b"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "515bd40b4ef56b879f5fa80888389d1557d7218b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937301163230149312"
},
{
"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"
}
}
[14:54:06.600] Scheduled build:
{
"id": "8771871101243404577",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-24T14:54:06.125221893Z",
"updateTime": "2023-08-24T14:54:06.125221893Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "515bd40b4ef56b879f5fa80888389d1557d7218b",
"ref": "refs/heads/main"
}
}
}
[14:54:06.600] Task URL: https://cr-buildbucket.appspot.com/build/8771871101243404577
[14:54:06.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:2:0) after 5m33s
[14:54:07.511] Received PubSub notification, asking Buildbucket for the build status
[14:54:07.562] Build status: SCHEDULED
[14:56:06.914] Received PubSub notification, asking Buildbucket for the build status
[14:56:06.945] Build status: STARTED
[14:59:39.629] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:2:0)
[14:59:39.662] Build status: STARTED
[14:59:39.662] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:5:0) after 8m46s
[15:08:25.751] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:5:0)
[15:08:25.791] Build status: STARTED
[15:08:25.791] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:6:0) after 9m18s
[15:17:43.811] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:6:0)
[15:17:43.853] Build status: STARTED
[15:17:43.853] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:7:0) after 4m45s
[15:22:28.916] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:7:0)
[15:22:28.948] Build status: STARTED
[15:22:28.948] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:8:0) after 1m18s
[15:23:47.171] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:8:0)
[15:23:47.197] Build status: STARTED
[15:23:47.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:9:0) after 9m43s
[15:33:30.316] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:9:0)
[15:33:30.495] Build status: STARTED
[15:33:30.495] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:10:0) after 3m1s
[15:36:31.517] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:10:0)
[15:36:31.559] Build status: STARTED
[15:36:31.559] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:11:0) after 5m16s
[15:41:47.643] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:11:0)
[15:41:47.687] Build status: STARTED
[15:41:47.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:12:0) after 6m59s
[15:48:46.705] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:12:0)
[15:48:46.722] Build status: STARTED
[15:48:46.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:13:0) after 9m30s
[15:58:16.921] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:13:0)
[15:58:16.944] Build status: STARTED
[15:58:16.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8937301163230149312:14:0) after 9m4s
[16:00:48.325] Received PubSub notification, asking Buildbucket for the build status
[16:00:48.347] Build:
{
"id": "8771871101243404577",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-24T14:54:06.125221893Z",
"startTime": "2023-08-24T14:56:06.338814Z",
"endTime": "2023-08-24T16:00:47.878626990Z",
"updateTime": "2023-08-24T16:00:47.878626990Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "515bd40b4ef56b879f5fa80888389d1557d7218b",
"ref": "refs/heads/main"
}
}
}
[16:00:48.347] Invocation finished in 1h6m43.50021203s with status SUCCEEDED