[06:40:27.601] New invocation is queued and will start shortly
[06:40:28.679] Starting the invocation (attempt 1)
[06:40:28.705] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/26474391aa5ba88b153fef4591bf690e9d9f0d59"
[06:40:28.705] Popped gitiles commit info from properties and tags
[06:40:28.705] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:40:28.705] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:40:28.705] Buildbucket request:
{
"requestId": "8939144158503921712",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8939144158503921712",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "7affa07cadd19c1413b3806e5cb6fb95f0a6fa8b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@7affa07cadd19c1413b3806e5cb6fb95f0a6fa8b",
"title": "7affa07cadd19c1413b3806e5cb6fb95f0a6fa8b",
"url": "https://llvm.googlesource.com/llvm-project/+/7affa07cadd19c1413b3806e5cb6fb95f0a6fa8b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "121e15f96ce401c875e717992a4d054e308ba775"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@121e15f96ce401c875e717992a4d054e308ba775",
"title": "121e15f96ce401c875e717992a4d054e308ba775",
"url": "https://llvm.googlesource.com/llvm-project/+/121e15f96ce401c875e717992a4d054e308ba775"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "26474391aa5ba88b153fef4591bf690e9d9f0d59"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@26474391aa5ba88b153fef4591bf690e9d9f0d59",
"title": "26474391aa5ba88b153fef4591bf690e9d9f0d59",
"url": "https://llvm.googlesource.com/llvm-project/+/26474391aa5ba88b153fef4591bf690e9d9f0d59"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "26474391aa5ba88b153fef4591bf690e9d9f0d59",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8939144158503921712"
},
{
"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"
}
}
[06:40:29.345] Scheduled build:
{
"id": "8773714096612598513",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-04T06:40:28.777618119Z",
"updateTime": "2023-08-04T06:40:28.777618119Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "26474391aa5ba88b153fef4591bf690e9d9f0d59",
"ref": "refs/heads/main"
}
}
}
[06:40:29.345] Task URL: https://cr-buildbucket.appspot.com/build/8773714096612598513
[06:40:29.345] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:2:0) after 9m42s
[06:40:30.238] Received PubSub notification, asking Buildbucket for the build status
[06:40:30.254] Build status: SCHEDULED
[06:42:36.163] Received PubSub notification, asking Buildbucket for the build status
[06:42:36.178] Build status: STARTED
[06:50:11.359] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:2:0)
[06:50:11.380] Build status: STARTED
[06:50:11.380] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:5:0) after 5m1s
[06:55:12.421] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:5:0)
[06:55:12.481] Build status: STARTED
[06:55:12.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:6:0) after 5m57s
[07:01:09.607] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:6:0)
[07:01:09.638] Build status: STARTED
[07:01:09.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:7:0) after 2m7s
[07:03:16.761] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:7:0)
[07:03:16.780] Build status: STARTED
[07:03:16.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:8:0) after 8m6s
[07:11:23.285] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:8:0)
[07:11:23.309] Build status: STARTED
[07:11:23.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:9:0) after 3m8s
[07:14:31.370] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:9:0)
[07:14:31.417] Build status: STARTED
[07:14:31.417] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:10:0) after 9m16s
[07:23:47.908] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:10:0)
[07:23:47.926] Build status: STARTED
[07:23:47.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:11:0) after 1m14s
[07:25:02.051] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:11:0)
[07:25:02.069] Build status: STARTED
[07:25:02.069] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:12:0) after 9m49s
[07:34:51.157] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:12:0)
[07:34:51.176] Build status: STARTED
[07:34:51.176] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:13:0) after 3m28s
[07:38:19.445] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:13:0)
[07:38:19.471] Build status: STARTED
[07:38:19.472] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:14:0) after 5m24s
[07:43:43.843] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:14:0)
[07:43:43.934] Build status: STARTED
[07:43:43.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8939144158503921712:15:0) after 5m42s
[07:45:18.764] Received PubSub notification, asking Buildbucket for the build status
[07:45:18.783] Build:
{
"id": "8773714096612598513",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-04T06:40:28.777618119Z",
"startTime": "2023-08-04T06:42:35.517578Z",
"endTime": "2023-08-04T07:45:18.493154689Z",
"updateTime": "2023-08-04T07:45:18.493154689Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "26474391aa5ba88b153fef4591bf690e9d9f0d59",
"ref": "refs/heads/main"
}
}
}
[07:45:18.783] Invocation finished in 1h4m51.193366133s with status SUCCEEDED