[21:27:46.866] New invocation is queued and will start shortly
[21:27:48.081] Starting the invocation (attempt 1)
[21:27:48.115] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/a291f00edaeeea622a9e3e2c706e3bdb25f93f09"
[21:27:48.115] Popped gitiles commit info from properties and tags
[21:27:48.115] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:27:48.115] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:27:48.116] Buildbucket request:
{
"requestId": "8898591489104561904",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8898591489104561904",
"job": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "76316ec91410bfe4775f828e3ebae5958a9d2a1d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@76316ec91410bfe4775f828e3ebae5958a9d2a1d",
"title": "76316ec91410bfe4775f828e3ebae5958a9d2a1d",
"url": "https://llvm.googlesource.com/llvm-project/+/76316ec91410bfe4775f828e3ebae5958a9d2a1d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "59b6c1b2d5290c40f59d2820b5144e2dec731635"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@59b6c1b2d5290c40f59d2820b5144e2dec731635",
"title": "59b6c1b2d5290c40f59d2820b5144e2dec731635",
"url": "https://llvm.googlesource.com/llvm-project/+/59b6c1b2d5290c40f59d2820b5144e2dec731635"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a14a83d9a102253eca7c02ff4c35a2ce3f7de6e5"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a14a83d9a102253eca7c02ff4c35a2ce3f7de6e5",
"title": "a14a83d9a102253eca7c02ff4c35a2ce3f7de6e5",
"url": "https://llvm.googlesource.com/llvm-project/+/a14a83d9a102253eca7c02ff4c35a2ce3f7de6e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "75d0281bc81f0040c24d15bdf9c5cc46e9237224"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@75d0281bc81f0040c24d15bdf9c5cc46e9237224",
"title": "75d0281bc81f0040c24d15bdf9c5cc46e9237224",
"url": "https://llvm.googlesource.com/llvm-project/+/75d0281bc81f0040c24d15bdf9c5cc46e9237224"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1b8cff9a52eab8718ba55996847ece0a96271bb7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1b8cff9a52eab8718ba55996847ece0a96271bb7",
"title": "1b8cff9a52eab8718ba55996847ece0a96271bb7",
"url": "https://llvm.googlesource.com/llvm-project/+/1b8cff9a52eab8718ba55996847ece0a96271bb7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4c8779388fd2f25730a1b044f9f10d6717833fce"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4c8779388fd2f25730a1b044f9f10d6717833fce",
"title": "4c8779388fd2f25730a1b044f9f10d6717833fce",
"url": "https://llvm.googlesource.com/llvm-project/+/4c8779388fd2f25730a1b044f9f10d6717833fce"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "118445841d4d50b57ea2892a18f0b656526f804d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@118445841d4d50b57ea2892a18f0b656526f804d",
"title": "118445841d4d50b57ea2892a18f0b656526f804d",
"url": "https://llvm.googlesource.com/llvm-project/+/118445841d4d50b57ea2892a18f0b656526f804d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a291f00edaeeea622a9e3e2c706e3bdb25f93f09"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a291f00edaeeea622a9e3e2c706e3bdb25f93f09",
"title": "a291f00edaeeea622a9e3e2c706e3bdb25f93f09",
"url": "https://llvm.googlesource.com/llvm-project/+/a291f00edaeeea622a9e3e2c706e3bdb25f93f09"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "a291f00edaeeea622a9e3e2c706e3bdb25f93f09",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8898591489104561904"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:27:48.708] Scheduled build:
{
"id": "8733161427023090673",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-24T21:27:48.226998504Z",
"updateTime": "2024-10-24T21:27:48.226998504Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "a291f00edaeeea622a9e3e2c706e3bdb25f93f09",
"ref": "refs/heads/main"
}
}
}
[21:27:48.708] Task URL: https://cr-buildbucket.appspot.com/build/8733161427023090673
[21:27:48.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:2:0) after 8m14s
[21:27:49.661] Received PubSub notification, asking Buildbucket for the build status
[21:27:49.689] Build status: SCHEDULED
[21:27:53.187] Received PubSub notification, asking Buildbucket for the build status
[21:27:53.210] Build status: STARTED
[21:36:02.746] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:2:0)
[21:36:02.763] Build status: STARTED
[21:36:02.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:5:0) after 3m5s
[21:39:07.776] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:5:0)
[21:39:07.968] Build status: STARTED
[21:39:07.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:6:0) after 8m25s
[21:47:33.034] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:6:0)
[21:47:33.054] Build status: STARTED
[21:47:33.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:7:0) after 3m0s
[21:50:33.068] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:7:0)
[21:50:33.087] Build status: STARTED
[21:50:33.087] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:8:0) after 2m29s
[21:53:02.102] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:8:0)
[21:53:02.122] Build status: STARTED
[21:53:02.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:9:0) after 5m28s
[21:58:30.140] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:9:0)
[21:58:30.228] Build status: STARTED
[21:58:30.228] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:10:0) after 4m1s
[22:02:31.263] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:10:0)
[22:02:31.285] Build status: STARTED
[22:02:31.285] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:11:0) after 9m48s
[22:12:19.339] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:11:0)
[22:12:19.359] Build status: STARTED
[22:12:19.359] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:12:0) after 7m29s
[22:19:48.381] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:12:0)
[22:19:48.400] Build status: STARTED
[22:19:48.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:13:0) after 2m44s
[22:22:32.475] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:13:0)
[22:22:32.540] Build status: STARTED
[22:22:32.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:14:0) after 2m29s
[22:25:01.558] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:14:0)
[22:25:01.581] Build status: STARTED
[22:25:01.581] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:15:0) after 5m26s
[22:30:27.687] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:15:0)
[22:30:27.715] Build status: STARTED
[22:30:27.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:16:0) after 2m10s
[22:32:37.730] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:16:0)
[22:32:37.797] Build status: STARTED
[22:32:37.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8898591489104561904:17:0) after 4m49s
[22:37:03.448] Received PubSub notification, asking Buildbucket for the build status
[22:37:03.545] Build:
{
"id": "8733161427023090673",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-24T21:27:48.226998504Z",
"startTime": "2024-10-24T21:27:52.996859879Z",
"endTime": "2024-10-24T22:37:02.907879956Z",
"updateTime": "2024-10-24T22:37:02.907879956Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "a291f00edaeeea622a9e3e2c706e3bdb25f93f09",
"ref": "refs/heads/main"
}
}
}
[22:37:03.545] Invocation finished in 1h9m16.687865423s with status SUCCEEDED