[06:00:08.929] New invocation is queued and will start shortly
[06:00:08.929] Triggered by user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[06:00:10.090] Starting the invocation (attempt 1)
[06:00:10.118] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/241e4c7466b877265e1645ca4709fe666c95c6c4"
[06:00:10.118] Reconstructed gitiles commit from tags
[06:00:10.118] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:00:10.118] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:00:10.118] Buildbucket request:
{
"requestId": "8925104164866542848",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "5f3036646d4973b2c7728e172e8aac2c42e14a1f7fadf8d08afe42dcde1060ab/578"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "159c0b356e95b26d38c6e4f19c2c4719adb7919c",
"project": "integration"
}
},
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8925104164866542848",
"job": "fuchsia/clang_toolchain.ci.core.x64-debug",
"triggers": [
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "02e5b03aae607b97a79b2f87918bfb3bfe06ff8d292d86c5d3958916908539e5/578"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "159c0b356e95b26d38c6e4f19c2c4719adb7919c",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "1637c0792550f70e4b2ef42b3d08aa91dd27f4a9"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/1637c0792550f70e4b2ef42b3d08aa91dd27f4a9",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "67bf670b-136a-4c4d-af4a-25f832f77c17",
"title": "clang-linux-x64/0"
},
{
"buildbucket": {
"properties": {
"$fuchsia/checkout": {
"clang_toolchain": {
"cas_digest": "5f3036646d4973b2c7728e172e8aac2c42e14a1f7fadf8d08afe42dcde1060ab/578"
},
"gitiles_commit": {
"host": "fuchsia.googlesource.com",
"id": "159c0b356e95b26d38c6e4f19c2c4719adb7919c",
"project": "integration"
}
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "241e4c7466b877265e1645ca4709fe666c95c6c4"
}
},
"tags": [
"buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/241e4c7466b877265e1645ca4709fe666c95c6c4",
"gitiles_ref:refs/heads/main",
"parent_buildername:clang-linux-x64",
"user_agent:recipe"
]
},
"id": "1ee13514-9219-4866-a599-203ee39abf9f",
"title": "clang-linux-x64/0"
}
]
},
"clang_toolchain": {
"git_repository": "https://llvm.googlesource.com/llvm-project",
"git_revision": "241e4c7466b877265e1645ca4709fe666c95c6c4"
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "241e4c7466b877265e1645ca4709fe666c95c6c4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8925104164866542848"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang_toolchain.ci.core.x64-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
},
{
"key": "parent_buildername",
"value": "clang-linux-x64"
},
{
"key": "user_agent",
"value": "recipe"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[06:00:10.805] Scheduled build:
{
"id": "8759674102880294033",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-06T06:00:10.202065925Z",
"updateTime": "2024-01-06T06:00:10.202065925Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "241e4c7466b877265e1645ca4709fe666c95c6c4",
"ref": "refs/heads/main"
}
}
}
[06:00:10.806] Task URL: https://cr-buildbucket.appspot.com/build/8759674102880294033
[06:00:10.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:2:0) after 3m25s
[06:00:11.068] Received PubSub notification, asking Buildbucket for the build status
[06:00:11.101] Build status: SCHEDULED
[06:00:30.341] Received PubSub notification, asking Buildbucket for the build status
[06:00:30.381] Build status: STARTED
[06:03:35.978] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:2:0)
[06:03:35.995] Build status: STARTED
[06:03:35.995] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:5:0) after 2m48s
[06:06:24.198] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:5:0)
[06:06:24.226] Build status: STARTED
[06:06:24.226] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:6:0) after 5m51s
[06:12:15.220] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:6:0)
[06:12:15.242] Build status: STARTED
[06:12:15.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:7:0) after 5m38s
[06:17:53.417] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:7:0)
[06:17:53.437] Build status: STARTED
[06:17:53.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:8:0) after 5m38s
[06:23:33.265] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:8:0)
[06:23:33.286] Build status: STARTED
[06:23:33.286] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:9:0) after 2m53s
[06:26:26.310] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:9:0)
[06:26:26.331] Build status: STARTED
[06:26:26.331] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:10:0) after 8m59s
[06:35:25.339] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:10:0)
[06:35:25.357] Build status: STARTED
[06:35:25.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:11:0) after 4m33s
[06:39:58.374] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:11:0)
[06:39:58.392] Build status: STARTED
[06:39:58.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:12:0) after 4m46s
[06:44:44.442] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:12:0)
[06:44:44.597] Build status: STARTED
[06:44:44.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:13:0) after 7m35s
[06:52:19.611] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:13:0)
[06:52:19.632] Build status: STARTED
[06:52:19.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:14:0) after 1m28s
[06:53:47.741] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:14:0)
[06:53:47.763] Build status: STARTED
[06:53:47.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:15:0) after 3m7s
[06:56:54.752] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:15:0)
[06:56:54.771] Build status: STARTED
[06:56:54.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:16:0) after 1m45s
[06:58:39.739] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:16:0)
[06:58:39.767] Build status: STARTED
[06:58:39.768] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:17:0) after 2m43s
[07:01:22.828] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:17:0)
[07:01:22.856] Build status: STARTED
[07:01:22.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:18:0) after 3m28s
[07:04:50.885] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:18:0)
[07:04:50.905] Build status: STARTED
[07:04:50.905] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:19:0) after 6m3s
[07:10:53.986] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:19:0)
[07:10:54.004] Build status: STARTED
[07:10:54.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:20:0) after 7m12s
[07:18:06.019] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:20:0)
[07:18:06.058] Build status: STARTED
[07:18:06.058] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:21:0) after 7m34s
[07:25:40.072] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:21:0)
[07:25:40.108] Build status: STARTED
[07:25:40.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:22:0) after 1m41s
[07:27:21.278] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:22:0)
[07:27:21.300] Build status: STARTED
[07:27:21.300] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:23:0) after 9m2s
[07:36:23.540] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:23:0)
[07:36:23.566] Build status: STARTED
[07:36:23.566] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:24:0) after 1m31s
[07:37:55.002] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:24:0)
[07:37:55.023] Build status: STARTED
[07:37:55.023] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:25:0) after 2m53s
[07:40:48.052] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:25:0)
[07:40:48.071] Build status: STARTED
[07:40:48.072] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:26:0) after 5m50s
[07:46:38.261] Handling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:26:0)
[07:46:38.282] Build status: STARTED
[07:46:38.282] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang_toolchain.ci.core.x64-debug:8925104164866542848:27:0) after 9m42s
[07:54:12.372] Received PubSub notification, asking Buildbucket for the build status
[07:54:12.394] Build:
{
"id": "8759674102880294033",
"builder": {
"project": "fuchsia",
"bucket": "ci",
"builder": "clang_toolchain.ci.core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-06T06:00:10.202065925Z",
"startTime": "2024-01-06T06:00:29.594593Z",
"endTime": "2024-01-06T07:54:11.288553Z",
"updateTime": "2024-01-06T07:54:11.288553Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "241e4c7466b877265e1645ca4709fe666c95c6c4",
"ref": "refs/heads/main"
}
}
}
[07:54:12.394] Invocation finished in 1h54m3.470636791s with status SUCCEEDED