[06:00:42.688] New invocation is queued and will start shortly
[06:00:44.131] Starting the invocation (attempt 1)
[06:00:44.192] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b"
[06:00:44.192] Popped gitiles commit info from properties and tags
[06:00:44.192] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:00:44.192] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:00:44.192] Buildbucket request:
{
"requestId": "8932261289814700240",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8932261289814700240",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d51855f70034ecd3f356ea154dff96174f4a47a0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d51855f70034ecd3f356ea154dff96174f4a47a0",
"title": "d51855f70034ecd3f356ea154dff96174f4a47a0",
"url": "https://llvm.googlesource.com/llvm-project/+/d51855f70034ecd3f356ea154dff96174f4a47a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "be215e76d7139f2429e06671620900f58202bdbb"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@be215e76d7139f2429e06671620900f58202bdbb",
"title": "be215e76d7139f2429e06671620900f58202bdbb",
"url": "https://llvm.googlesource.com/llvm-project/+/be215e76d7139f2429e06671620900f58202bdbb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "f4231bf446ed8663886a86eb5b8b2db57baff854"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@f4231bf446ed8663886a86eb5b8b2db57baff854",
"title": "f4231bf446ed8663886a86eb5b8b2db57baff854",
"url": "https://llvm.googlesource.com/llvm-project/+/f4231bf446ed8663886a86eb5b8b2db57baff854"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1be3b1ef51eb62dd713b6b221c349695ad39d7a1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1be3b1ef51eb62dd713b6b221c349695ad39d7a1",
"title": "1be3b1ef51eb62dd713b6b221c349695ad39d7a1",
"url": "https://llvm.googlesource.com/llvm-project/+/1be3b1ef51eb62dd713b6b221c349695ad39d7a1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "80b2aac2c671771d74bc5d7426f7bd4ffa0b8a8e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@80b2aac2c671771d74bc5d7426f7bd4ffa0b8a8e",
"title": "80b2aac2c671771d74bc5d7426f7bd4ffa0b8a8e",
"url": "https://llvm.googlesource.com/llvm-project/+/80b2aac2c671771d74bc5d7426f7bd4ffa0b8a8e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b",
"title": "cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b",
"url": "https://llvm.googlesource.com/llvm-project/+/cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8932261289814700240"
},
{
"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:00:44.842] Scheduled build:
{
"id": "8766831227460160465",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T06:00:44.310571395Z",
"updateTime": "2023-10-19T06:00:44.310571395Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b",
"ref": "refs/heads/main"
}
}
}
[06:00:44.842] Task URL: https://cr-buildbucket.appspot.com/build/8766831227460160465
[06:00:44.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:2:0) after 3m53s
[06:00:45.012] Received PubSub notification, asking Buildbucket for the build status
[06:00:45.048] Build status: SCHEDULED
[06:04:38.667] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:2:0)
[06:04:38.685] Build status: SCHEDULED
[06:04:38.685] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:4:0) after 5m49s
[06:10:28.143] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:4:0)
[06:10:28.167] Build status: SCHEDULED
[06:10:28.167] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:5:0) after 5m58s
[06:16:26.417] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:5:0)
[06:16:26.434] Build status: SCHEDULED
[06:16:26.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:6:0) after 5m56s
[06:22:22.625] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:6:0)
[06:22:22.649] Build status: SCHEDULED
[06:22:22.649] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:7:0) after 6m0s
[06:28:22.934] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:7:0)
[06:28:22.967] Build status: SCHEDULED
[06:28:22.967] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:8:0) after 6m39s
[06:35:01.986] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:8:0)
[06:35:02.008] Build status: SCHEDULED
[06:35:02.008] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:9:0) after 2m46s
[06:35:52.598] Received PubSub notification, asking Buildbucket for the build status
[06:35:52.613] Build status: STARTED
[06:37:48.124] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:9:0)
[06:37:48.152] Build status: STARTED
[06:37:48.152] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:11:0) after 1m22s
[06:39:10.172] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:11:0)
[06:39:10.188] Build status: STARTED
[06:39:10.188] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:12:0) after 2m8s
[06:41:18.433] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:12:0)
[06:41:18.457] Build status: STARTED
[06:41:18.457] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:13:0) after 8m32s
[06:49:50.664] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:13:0)
[06:49:50.681] Build status: STARTED
[06:49:50.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:14:0) after 7m17s
[06:57:07.918] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:14:0)
[06:57:07.938] Build status: STARTED
[06:57:07.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:15:0) after 5m45s
[07:02:53.111] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:15:0)
[07:02:53.139] Build status: STARTED
[07:02:53.139] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:16:0) after 8m55s
[07:11:48.459] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:16:0)
[07:11:48.488] Build status: STARTED
[07:11:48.488] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:17:0) after 6m57s
[07:18:45.507] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:17:0)
[07:18:45.547] Build status: STARTED
[07:18:45.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:18:0) after 1m32s
[07:20:17.567] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:18:0)
[07:20:17.590] Build status: STARTED
[07:20:17.590] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:19:0) after 3m46s
[07:24:03.630] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:19:0)
[07:24:03.647] Build status: STARTED
[07:24:03.647] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:20:0) after 8m26s
[07:32:29.673] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:20:0)
[07:32:29.691] Build status: STARTED
[07:32:29.691] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:21:0) after 4m9s
[07:36:38.711] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:21:0)
[07:36:38.732] Build status: STARTED
[07:36:38.732] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:22:0) after 1m40s
[07:38:18.792] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:22:0)
[07:38:18.856] Build status: STARTED
[07:38:18.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8932261289814700240:23:0) after 8m10s
[07:42:28.100] Received PubSub notification, asking Buildbucket for the build status
[07:42:28.146] Build:
{
"id": "8766831227460160465",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-10-19T06:00:44.310571395Z",
"startTime": "2023-10-19T06:35:50.740673Z",
"endTime": "2023-10-19T07:42:27.591645610Z",
"updateTime": "2023-10-19T07:42:27.591645610Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "cffb9df10cebe4cba7c5eb6cc7a9a66f4c253f2b",
"ref": "refs/heads/main"
}
}
}
[07:42:28.146] Invocation finished in 1h41m45.465036076s with status SUCCEEDED