[01:52:14.174] New invocation is queued and will start shortly
[01:52:15.261] Starting the invocation (attempt 1)
[01:52:15.288] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/36b37c775c285bbff9b57630e7ea9d00b918cc91"
[01:52:15.288] Popped gitiles commit info from properties and tags
[01:52:15.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:52:15.288] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:52:15.288] Buildbucket request:
{
"requestId": "8934904234558317632",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8934904234558317632",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c2e92cb490b1d9a7ecf0c97d3c2d2dc6d2d4fc70"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c2e92cb490b1d9a7ecf0c97d3c2d2dc6d2d4fc70",
"title": "c2e92cb490b1d9a7ecf0c97d3c2d2dc6d2d4fc70",
"url": "https://llvm.googlesource.com/llvm-project/+/c2e92cb490b1d9a7ecf0c97d3c2d2dc6d2d4fc70"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dd477ebd235e17e9a87cada3fe83fcd4491e53d4"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dd477ebd235e17e9a87cada3fe83fcd4491e53d4",
"title": "dd477ebd235e17e9a87cada3fe83fcd4491e53d4",
"url": "https://llvm.googlesource.com/llvm-project/+/dd477ebd235e17e9a87cada3fe83fcd4491e53d4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "b927490d73eebe0a7e8071047d79a330145b0488"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@b927490d73eebe0a7e8071047d79a330145b0488",
"title": "b927490d73eebe0a7e8071047d79a330145b0488",
"url": "https://llvm.googlesource.com/llvm-project/+/b927490d73eebe0a7e8071047d79a330145b0488"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "613a09d99e880850d71d5d40a0605d3c399fc021"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@613a09d99e880850d71d5d40a0605d3c399fc021",
"title": "613a09d99e880850d71d5d40a0605d3c399fc021",
"url": "https://llvm.googlesource.com/llvm-project/+/613a09d99e880850d71d5d40a0605d3c399fc021"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "ab94fbba572cd50a1e85dbc023915b1f76b98106"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@ab94fbba572cd50a1e85dbc023915b1f76b98106",
"title": "ab94fbba572cd50a1e85dbc023915b1f76b98106",
"url": "https://llvm.googlesource.com/llvm-project/+/ab94fbba572cd50a1e85dbc023915b1f76b98106"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "36b37c775c285bbff9b57630e7ea9d00b918cc91"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@36b37c775c285bbff9b57630e7ea9d00b918cc91",
"title": "36b37c775c285bbff9b57630e7ea9d00b918cc91",
"url": "https://llvm.googlesource.com/llvm-project/+/36b37c775c285bbff9b57630e7ea9d00b918cc91"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "36b37c775c285bbff9b57630e7ea9d00b918cc91",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8934904234558317632"
},
{
"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"
}
}
[01:52:15.757] Scheduled build:
{
"id": "8769474172676935649",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-20T01:52:15.338445419Z",
"updateTime": "2023-09-20T01:52:15.338445419Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "36b37c775c285bbff9b57630e7ea9d00b918cc91",
"ref": "refs/heads/main"
}
}
}
[01:52:15.758] Task URL: https://cr-buildbucket.appspot.com/build/8769474172676935649
[01:52:15.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:2:0) after 2m41s
[01:52:16.677] Received PubSub notification, asking Buildbucket for the build status
[01:52:16.722] Build status: SCHEDULED
[01:54:56.917] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:2:0)
[01:54:56.956] Build status: SCHEDULED
[01:54:56.956] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:4:0) after 8m49s
[02:03:45.976] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:4:0)
[02:03:46.005] Build status: SCHEDULED
[02:03:46.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:5:0) after 5m8s
[02:08:54.051] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:5:0)
[02:08:54.076] Build status: SCHEDULED
[02:08:54.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:6:0) after 1m32s
[02:10:26.092] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:6:0)
[02:10:26.125] Build status: SCHEDULED
[02:10:26.125] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:7:0) after 6m7s
[02:16:33.207] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:7:0)
[02:16:33.233] Build status: SCHEDULED
[02:16:33.233] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:8:0) after 5m29s
[02:22:02.682] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:8:0)
[02:22:02.709] Build status: SCHEDULED
[02:22:02.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:9:0) after 2m11s
[02:24:14.073] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:9:0)
[02:24:14.157] Build status: SCHEDULED
[02:24:14.157] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:10:0) after 7m4s
[02:31:18.313] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:10:0)
[02:31:18.338] Build status: SCHEDULED
[02:31:18.338] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:11:0) after 5m27s
[02:34:30.920] Received PubSub notification, asking Buildbucket for the build status
[02:34:30.940] Build status: STARTED
[02:36:45.618] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:11:0)
[02:36:45.638] Build status: STARTED
[02:36:45.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:13:0) after 8m41s
[02:45:26.752] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:13:0)
[02:45:26.778] Build status: STARTED
[02:45:26.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:14:0) after 8m8s
[02:53:35.278] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:14:0)
[02:53:35.298] Build status: STARTED
[02:53:35.298] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:15:0) after 3m3s
[02:56:38.681] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:15:0)
[02:56:38.714] Build status: STARTED
[02:56:38.714] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:16:0) after 8m8s
[03:04:46.834] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:16:0)
[03:04:46.850] Build status: STARTED
[03:04:46.850] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:17:0) after 8m59s
[03:13:46.038] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:17:0)
[03:13:46.056] Build status: STARTED
[03:13:46.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:18:0) after 6m18s
[03:20:04.435] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:18:0)
[03:20:04.467] Build status: STARTED
[03:20:04.467] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:19:0) after 3m1s
[03:23:05.543] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:19:0)
[03:23:05.565] Build status: STARTED
[03:23:05.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:20:0) after 1m41s
[03:24:46.743] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:20:0)
[03:24:46.765] Build status: STARTED
[03:24:46.765] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:21:0) after 3m6s
[03:27:52.784] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:21:0)
[03:27:52.805] Build status: STARTED
[03:27:52.805] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:22:0) after 2m36s
[03:30:28.924] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:22:0)
[03:30:28.942] Build status: STARTED
[03:30:28.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:23:0) after 4m20s
[03:34:49.234] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:23:0)
[03:34:49.259] Build status: STARTED
[03:34:49.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:24:0) after 3m47s
[03:38:36.282] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:24:0)
[03:38:36.301] Build status: STARTED
[03:38:36.301] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:25:0) after 1m5s
[03:39:41.697] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:25:0)
[03:39:41.720] Build status: STARTED
[03:39:41.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8934904234558317632:26:0) after 7m36s
[03:41:22.628] Received PubSub notification, asking Buildbucket for the build status
[03:41:22.660] Build:
{
"id": "8769474172676935649",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-20T01:52:15.338445419Z",
"startTime": "2023-09-20T02:34:30.228482Z",
"endTime": "2023-09-20T03:41:22.051060429Z",
"updateTime": "2023-09-20T03:41:22.051060429Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "36b37c775c285bbff9b57630e7ea9d00b918cc91",
"ref": "refs/heads/main"
}
}
}
[03:41:22.660] Invocation finished in 1h49m8.50029981s with status SUCCEEDED