[20:18:44.779] New invocation is queued and will start shortly
[20:18:45.837] Starting the invocation (attempt 1)
[20:18:45.861] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/45c01e8a33bbb1790ea16577e47b1e6a34fa1548"
[20:18:45.861] Popped gitiles commit info from properties and tags
[20:18:45.861] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:18:45.861] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:18:45.861] Buildbucket request:
{
"requestId": "8893522402273263856",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8893522402273263856",
"job": "fuchsia/toolchain.ci-clang-windows-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b71c44b9be17dc6295eb733d685b38e797f3c846"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b71c44b9be17dc6295eb733d685b38e797f3c846",
"title": "b71c44b9be17dc6295eb733d685b38e797f3c846",
"url": "https://llvm.googlesource.com/llvm-project/+/b71c44b9be17dc6295eb733d685b38e797f3c846"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1808255a44e67446715cb6b16df49c6cec41b0b4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1808255a44e67446715cb6b16df49c6cec41b0b4",
"title": "1808255a44e67446715cb6b16df49c6cec41b0b4",
"url": "https://llvm.googlesource.com/llvm-project/+/1808255a44e67446715cb6b16df49c6cec41b0b4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "395a369056e1a9b55015b81a8667f39f1f48457f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@395a369056e1a9b55015b81a8667f39f1f48457f",
"title": "395a369056e1a9b55015b81a8667f39f1f48457f",
"url": "https://llvm.googlesource.com/llvm-project/+/395a369056e1a9b55015b81a8667f39f1f48457f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "98c97d4a19412a76f7279003af6cb219dea1f0c3"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@98c97d4a19412a76f7279003af6cb219dea1f0c3",
"title": "98c97d4a19412a76f7279003af6cb219dea1f0c3",
"url": "https://llvm.googlesource.com/llvm-project/+/98c97d4a19412a76f7279003af6cb219dea1f0c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "aa07f922103ebe8e78c8da4c754b43af3c129f3e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@aa07f922103ebe8e78c8da4c754b43af3c129f3e",
"title": "aa07f922103ebe8e78c8da4c754b43af3c129f3e",
"url": "https://llvm.googlesource.com/llvm-project/+/aa07f922103ebe8e78c8da4c754b43af3c129f3e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d3508ccd1512c57094ec7b321d147aa72c9fbc7e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d3508ccd1512c57094ec7b321d147aa72c9fbc7e",
"title": "d3508ccd1512c57094ec7b321d147aa72c9fbc7e",
"url": "https://llvm.googlesource.com/llvm-project/+/d3508ccd1512c57094ec7b321d147aa72c9fbc7e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b5d02786be31f45ca5919b3b73e99d8958330f78"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b5d02786be31f45ca5919b3b73e99d8958330f78",
"title": "b5d02786be31f45ca5919b3b73e99d8958330f78",
"url": "https://llvm.googlesource.com/llvm-project/+/b5d02786be31f45ca5919b3b73e99d8958330f78"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "45c01e8a33bbb1790ea16577e47b1e6a34fa1548"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@45c01e8a33bbb1790ea16577e47b1e6a34fa1548",
"title": "45c01e8a33bbb1790ea16577e47b1e6a34fa1548",
"url": "https://llvm.googlesource.com/llvm-project/+/45c01e8a33bbb1790ea16577e47b1e6a34fa1548"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "45c01e8a33bbb1790ea16577e47b1e6a34fa1548",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8893522402273263856"
},
{
"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"
}
}
[20:18:46.298] Scheduled build:
{
"id": "8728092340435274561",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-19T20:18:45.912346531Z",
"updateTime": "2024-12-19T20:18:45.912346531Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "45c01e8a33bbb1790ea16577e47b1e6a34fa1548",
"ref": "refs/heads/main"
}
}
}
[20:18:46.298] Task URL: https://cr-buildbucket.appspot.com/build/8728092340435274561
[20:18:46.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:2:0) after 1m58s
[20:18:47.244] Received PubSub notification, asking Buildbucket for the build status
[20:18:47.328] Build status: SCHEDULED
[20:18:50.869] Received PubSub notification, asking Buildbucket for the build status
[20:18:50.993] Build status: STARTED
[20:20:44.756] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:2:0)
[20:20:44.949] Build status: STARTED
[20:20:44.950] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:5:0) after 8m1s
[20:28:46.036] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:5:0)
[20:28:46.055] Build status: STARTED
[20:28:46.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:6:0) after 5m21s
[20:34:07.266] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:6:0)
[20:34:07.291] Build status: STARTED
[20:34:07.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:7:0) after 9m49s
[20:43:56.432] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:7:0)
[20:43:56.455] Build status: STARTED
[20:43:56.455] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:8:0) after 4m20s
[20:48:16.618] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:8:0)
[20:48:16.640] Build status: STARTED
[20:48:16.640] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:9:0) after 3m42s
[20:51:58.654] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:9:0)
[20:51:58.671] Build status: STARTED
[20:51:58.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:10:0) after 1m36s
[20:53:34.686] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:10:0)
[20:53:34.704] Build status: STARTED
[20:53:34.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:11:0) after 4m3s
[20:57:37.781] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:11:0)
[20:57:37.799] Build status: STARTED
[20:57:37.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:12:0) after 1m55s
[20:59:32.862] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:12:0)
[20:59:32.893] Build status: STARTED
[20:59:32.893] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:13:0) after 5m38s
[21:05:10.963] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:13:0)
[21:05:11.009] Build status: STARTED
[21:05:11.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:14:0) after 7m14s
[21:12:25.025] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:14:0)
[21:12:25.046] Build status: STARTED
[21:12:25.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:15:0) after 5m47s
[21:18:12.065] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:15:0)
[21:18:12.096] Build status: STARTED
[21:18:12.096] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:16:0) after 9m27s
[21:27:39.120] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:16:0)
[21:27:39.148] Build status: STARTED
[21:27:39.148] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:17:0) after 1m8s
[21:28:47.187] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:17:0)
[21:28:47.220] Build status: STARTED
[21:28:47.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:18:0) after 2m57s
[21:31:44.336] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:18:0)
[21:31:44.362] Build status: STARTED
[21:31:44.362] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:19:0) after 5m3s
[21:36:47.379] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:19:0)
[21:36:47.397] Build status: STARTED
[21:36:47.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:20:0) after 9m36s
[21:46:23.429] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:20:0)
[21:46:23.452] Build status: STARTED
[21:46:23.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:21:0) after 3m40s
[21:50:03.524] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:21:0)
[21:50:03.542] Build status: STARTED
[21:50:03.542] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-windows-x64-40be1bb5:8893522402273263856:22:0) after 6m14s
[21:50:05.005] Received PubSub notification, asking Buildbucket for the build status
[21:50:05.037] Build:
{
"id": "8728092340435274561",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-windows-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-19T20:18:45.912346531Z",
"startTime": "2024-12-19T20:18:50.436633488Z",
"endTime": "2024-12-19T21:50:04.659414677Z",
"updateTime": "2024-12-19T21:50:04.659414677Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "45c01e8a33bbb1790ea16577e47b1e6a34fa1548",
"ref": "refs/heads/main"
}
}
}
[21:50:05.037] Invocation finished in 1h31m20.261080456s with status SUCCEEDED