[16:25:34.703] New invocation is queued and will start shortly
[16:25:36.137] Starting the invocation (attempt 1)
[16:25:36.179] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/6ce418113746c1d8a85012d1f8e22270eb5fdaf1"
[16:25:36.179] Popped gitiles commit info from properties and tags
[16:25:36.179] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:25:36.180] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:25:36.180] Buildbucket request:
{
"requestId": "8922256310283178128",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8922256310283178128",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b1acb7a315e903ee340a33dbc9b2b61b0450bb67"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b1acb7a315e903ee340a33dbc9b2b61b0450bb67",
"title": "b1acb7a315e903ee340a33dbc9b2b61b0450bb67",
"url": "https://llvm.googlesource.com/llvm-project/+/b1acb7a315e903ee340a33dbc9b2b61b0450bb67"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d4c5acac99e83ffa12d2d720c9e502a181cbd7ea"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d4c5acac99e83ffa12d2d720c9e502a181cbd7ea",
"title": "d4c5acac99e83ffa12d2d720c9e502a181cbd7ea",
"url": "https://llvm.googlesource.com/llvm-project/+/d4c5acac99e83ffa12d2d720c9e502a181cbd7ea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "d6c7253d32e4bdff619c39708170f1c1fa01ff95"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@d6c7253d32e4bdff619c39708170f1c1fa01ff95",
"title": "d6c7253d32e4bdff619c39708170f1c1fa01ff95",
"url": "https://llvm.googlesource.com/llvm-project/+/d6c7253d32e4bdff619c39708170f1c1fa01ff95"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "40fd17a90d4dcfb4bada663d73111a43c4c6ccb1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@40fd17a90d4dcfb4bada663d73111a43c4c6ccb1",
"title": "40fd17a90d4dcfb4bada663d73111a43c4c6ccb1",
"url": "https://llvm.googlesource.com/llvm-project/+/40fd17a90d4dcfb4bada663d73111a43c4c6ccb1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9ea34be3e4c9deeabfc21cced1acb7f9593ffe93"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9ea34be3e4c9deeabfc21cced1acb7f9593ffe93",
"title": "9ea34be3e4c9deeabfc21cced1acb7f9593ffe93",
"url": "https://llvm.googlesource.com/llvm-project/+/9ea34be3e4c9deeabfc21cced1acb7f9593ffe93"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "e5638c5a00682243b1ee012d7dd8292aa221dff8"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@e5638c5a00682243b1ee012d7dd8292aa221dff8",
"title": "e5638c5a00682243b1ee012d7dd8292aa221dff8",
"url": "https://llvm.googlesource.com/llvm-project/+/e5638c5a00682243b1ee012d7dd8292aa221dff8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "36e8db7d8c9183c66363e76517772b074b4f53be"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@36e8db7d8c9183c66363e76517772b074b4f53be",
"title": "36e8db7d8c9183c66363e76517772b074b4f53be",
"url": "https://llvm.googlesource.com/llvm-project/+/36e8db7d8c9183c66363e76517772b074b4f53be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "388548359f5049b88a9738d8a9e67691503fbdef"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@388548359f5049b88a9738d8a9e67691503fbdef",
"title": "388548359f5049b88a9738d8a9e67691503fbdef",
"url": "https://llvm.googlesource.com/llvm-project/+/388548359f5049b88a9738d8a9e67691503fbdef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "299e5fef9dee3f2d3af8f9162075b8f1cfa34446"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@299e5fef9dee3f2d3af8f9162075b8f1cfa34446",
"title": "299e5fef9dee3f2d3af8f9162075b8f1cfa34446",
"url": "https://llvm.googlesource.com/llvm-project/+/299e5fef9dee3f2d3af8f9162075b8f1cfa34446"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6ce418113746c1d8a85012d1f8e22270eb5fdaf1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6ce418113746c1d8a85012d1f8e22270eb5fdaf1",
"title": "6ce418113746c1d8a85012d1f8e22270eb5fdaf1",
"url": "https://llvm.googlesource.com/llvm-project/+/6ce418113746c1d8a85012d1f8e22270eb5fdaf1"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6ce418113746c1d8a85012d1f8e22270eb5fdaf1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8922256310283178128"
},
{
"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"
}
}
[16:25:36.887] Scheduled build:
{
"id": "8756826247884809217",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-06T16:25:36.368587797Z",
"updateTime": "2024-02-06T16:25:36.368587797Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6ce418113746c1d8a85012d1f8e22270eb5fdaf1",
"ref": "refs/heads/main"
}
}
}
[16:25:36.887] Task URL: https://cr-buildbucket.appspot.com/build/8756826247884809217
[16:25:36.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:2:0) after 5m1s
[16:25:37.818] Received PubSub notification, asking Buildbucket for the build status
[16:25:37.838] Build status: SCHEDULED
[16:30:37.954] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:2:0)
[16:30:37.987] Build status: SCHEDULED
[16:30:37.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:4:0) after 7m33s
[16:34:21.443] Received PubSub notification, asking Buildbucket for the build status
[16:34:21.462] Build status: STARTED
[16:38:11.028] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:4:0)
[16:38:11.164] Build status: STARTED
[16:38:11.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:6:0) after 1m46s
[16:39:57.214] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:6:0)
[16:39:57.236] Build status: STARTED
[16:39:57.236] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:7:0) after 1m40s
[16:41:37.257] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:7:0)
[16:41:37.275] Build status: STARTED
[16:41:37.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:8:0) after 3m17s
[16:44:54.300] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:8:0)
[16:44:54.331] Build status: STARTED
[16:44:54.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:9:0) after 5m11s
[16:50:05.373] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:9:0)
[16:50:05.394] Build status: STARTED
[16:50:05.394] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:10:0) after 4m53s
[16:54:58.442] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:10:0)
[16:54:58.462] Build status: STARTED
[16:54:58.462] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:11:0) after 3m8s
[16:58:06.487] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:11:0)
[16:58:06.525] Build status: STARTED
[16:58:06.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:12:0) after 3m8s
[17:01:14.620] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:12:0)
[17:01:14.663] Build status: STARTED
[17:01:14.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:13:0) after 7m11s
[17:08:25.680] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:13:0)
[17:08:25.738] Build status: STARTED
[17:08:25.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:14:0) after 1m25s
[17:09:50.816] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:14:0)
[17:09:50.864] Build status: STARTED
[17:09:50.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:15:0) after 3m57s
[17:13:47.882] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:15:0)
[17:13:47.906] Build status: STARTED
[17:13:47.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:16:0) after 2m44s
[17:16:31.927] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:16:0)
[17:16:31.968] Build status: STARTED
[17:16:31.968] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:17:0) after 6m53s
[17:23:25.001] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:17:0)
[17:23:25.026] Build status: STARTED
[17:23:25.027] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:18:0) after 7m35s
[17:30:59.997] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:18:0)
[17:31:00.024] Build status: STARTED
[17:31:00.024] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:19:0) after 5m3s
[17:36:03.044] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:19:0)
[17:36:03.122] Build status: STARTED
[17:36:03.122] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:20:0) after 6m55s
[17:42:58.226] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:20:0)
[17:42:58.303] Build status: STARTED
[17:42:58.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8922256310283178128:21:0) after 3m15s
[17:45:59.489] Received PubSub notification, asking Buildbucket for the build status
[17:45:59.533] Build:
{
"id": "8756826247884809217",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2024-02-06T16:25:36.368587797Z",
"startTime": "2024-02-06T16:34:20.543912Z",
"endTime": "2024-02-06T17:45:58.410879Z",
"updateTime": "2024-02-06T17:45:58.410879Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "6ce418113746c1d8a85012d1f8e22270eb5fdaf1",
"ref": "refs/heads/main"
}
}
}
[17:45:59.533] Invocation finished in 1h20m24.835680702s with status SUCCEEDED