[16:37:30.301] New invocation is queued and will start shortly
[16:37:31.439] Starting the invocation (attempt 1)
[16:37:31.467] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/61a98eb7d3a6e42b4a0a0c651bd62411f783c166"
[16:37:31.467] Popped gitiles commit info from properties and tags
[16:37:31.467] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:37:31.467] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:37:31.468] Buildbucket request:
{
"requestId": "8879040806960630896",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-linux-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8879040806960630896",
"job": "fuchsia/toolchain.ci-clang-linux-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4608df5b9fea7322714fc5fe7e6bcc5e5e3a666a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4608df5b9fea7322714fc5fe7e6bcc5e5e3a666a",
"title": "4608df5b9fea7322714fc5fe7e6bcc5e5e3a666a",
"url": "https://llvm.googlesource.com/llvm-project/+/4608df5b9fea7322714fc5fe7e6bcc5e5e3a666a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "4ad230bebd1dcdf09f445181f34e8fa92e970412"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@4ad230bebd1dcdf09f445181f34e8fa92e970412",
"title": "4ad230bebd1dcdf09f445181f34e8fa92e970412",
"url": "https://llvm.googlesource.com/llvm-project/+/4ad230bebd1dcdf09f445181f34e8fa92e970412"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f64cd30367e7cee4a0b82b405d96e9b37412b391"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f64cd30367e7cee4a0b82b405d96e9b37412b391",
"title": "f64cd30367e7cee4a0b82b405d96e9b37412b391",
"url": "https://llvm.googlesource.com/llvm-project/+/f64cd30367e7cee4a0b82b405d96e9b37412b391"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "caaca3298e4c08936d8f10df4ebc1749c8a66902"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@caaca3298e4c08936d8f10df4ebc1749c8a66902",
"title": "caaca3298e4c08936d8f10df4ebc1749c8a66902",
"url": "https://llvm.googlesource.com/llvm-project/+/caaca3298e4c08936d8f10df4ebc1749c8a66902"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "a1191b48755a8ce651792ba06a430338f41af2f1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@a1191b48755a8ce651792ba06a430338f41af2f1",
"title": "a1191b48755a8ce651792ba06a430338f41af2f1",
"url": "https://llvm.googlesource.com/llvm-project/+/a1191b48755a8ce651792ba06a430338f41af2f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "63385e7fc94039e1af6a8f21bebf8974f9d4a2a7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@63385e7fc94039e1af6a8f21bebf8974f9d4a2a7",
"title": "63385e7fc94039e1af6a8f21bebf8974f9d4a2a7",
"url": "https://llvm.googlesource.com/llvm-project/+/63385e7fc94039e1af6a8f21bebf8974f9d4a2a7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1610d41d8397301c3c39486b7ed1d329f43aad5d"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1610d41d8397301c3c39486b7ed1d329f43aad5d",
"title": "1610d41d8397301c3c39486b7ed1d329f43aad5d",
"url": "https://llvm.googlesource.com/llvm-project/+/1610d41d8397301c3c39486b7ed1d329f43aad5d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "61a98eb7d3a6e42b4a0a0c651bd62411f783c166"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@61a98eb7d3a6e42b4a0a0c651bd62411f783c166",
"title": "61a98eb7d3a6e42b4a0a0c651bd62411f783c166",
"url": "https://llvm.googlesource.com/llvm-project/+/61a98eb7d3a6e42b4a0a0c651bd62411f783c166"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "61a98eb7d3a6e42b4a0a0c651bd62411f783c166",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8879040806960630896"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/toolchain.ci-clang-linux-x64-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:37:32.784] Scheduled build:
{
"id": "8713610744976800945",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-linux-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-05-28T16:37:31.562577524Z",
"updateTime": "2025-05-28T16:37:31.562577524Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "61a98eb7d3a6e42b4a0a0c651bd62411f783c166",
"ref": "refs/heads/main"
}
}
}
[16:37:32.784] Task URL: https://cr-buildbucket.appspot.com/build/8713610744976800945
[16:37:32.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:2:0) after 6m18s
[16:37:33.471] Received PubSub notification, asking Buildbucket for the build status
[16:37:33.494] Build status: SCHEDULED
[16:37:35.604] Received PubSub notification, asking Buildbucket for the build status
[16:37:35.636] Build status: STARTED
[16:43:50.863] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:2:0)
[16:43:50.894] Build status: STARTED
[16:43:50.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:5:0) after 1m30s
[16:45:20.999] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:5:0)
[16:45:21.026] Build status: STARTED
[16:45:21.026] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:6:0) after 8m36s
[16:53:57.047] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:6:0)
[16:53:57.100] Build status: STARTED
[16:53:57.100] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:7:0) after 4m18s
[16:58:15.193] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:7:0)
[16:58:15.220] Build status: STARTED
[16:58:15.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:8:0) after 6m21s
[17:04:36.285] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:8:0)
[17:04:36.315] Build status: STARTED
[17:04:36.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:9:0) after 2m29s
[17:07:05.389] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:9:0)
[17:07:05.425] Build status: STARTED
[17:07:05.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-linux-x64-40be1bb5:8879040806960630896:10:0) after 5m33s
[17:07:27.068] Received PubSub notification, asking Buildbucket for the build status
[17:07:27.092] Build:
{
"id": "8713610744976800945",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-linux-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-05-28T16:37:31.562577524Z",
"startTime": "2025-05-28T16:37:35.353828308Z",
"endTime": "2025-05-28T17:07:26.675888346Z",
"updateTime": "2025-05-28T17:07:26.675888346Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "61a98eb7d3a6e42b4a0a0c651bd62411f783c166",
"ref": "refs/heads/main"
}
}
}
[17:07:27.092] Invocation finished in 29m56.805545579s with status SUCCEEDED