[12:59:41.013] New invocation is queued and will start shortly
[12:59:42.580] Starting the invocation (attempt 1)
[12:59:42.606] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/2cf71fcb9751a347f39035739f8865ef7a53ee68"
[12:59:42.606] Popped gitiles commit info from properties and tags
[12:59:42.606] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:59:42.607] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:59:42.607] Buildbucket request:
{
"requestId": "8867911084235116320",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8867911084235116320",
"job": "fuchsia/toolchain.ci-clang-mac-x64-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "58805dd9ede08df777e3e4486493b3a70c5124c7"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@58805dd9ede08df777e3e4486493b3a70c5124c7",
"title": "58805dd9ede08df777e3e4486493b3a70c5124c7",
"url": "https://llvm.googlesource.com/llvm-project/+/58805dd9ede08df777e3e4486493b3a70c5124c7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3407fedb8628fab23a251ee804ee280250966c40"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3407fedb8628fab23a251ee804ee280250966c40",
"title": "3407fedb8628fab23a251ee804ee280250966c40",
"url": "https://llvm.googlesource.com/llvm-project/+/3407fedb8628fab23a251ee804ee280250966c40"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "6167f0c818051e378fd8b0efb6e93adf13e53fae"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@6167f0c818051e378fd8b0efb6e93adf13e53fae",
"title": "6167f0c818051e378fd8b0efb6e93adf13e53fae",
"url": "https://llvm.googlesource.com/llvm-project/+/6167f0c818051e378fd8b0efb6e93adf13e53fae"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "062c0fcf4b8b29a12191d782f4e23623cef061db"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@062c0fcf4b8b29a12191d782f4e23623cef061db",
"title": "062c0fcf4b8b29a12191d782f4e23623cef061db",
"url": "https://llvm.googlesource.com/llvm-project/+/062c0fcf4b8b29a12191d782f4e23623cef061db"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "60e41d241c3a4ba63e777986688ec75cc0612d0f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@60e41d241c3a4ba63e777986688ec75cc0612d0f",
"title": "60e41d241c3a4ba63e777986688ec75cc0612d0f",
"url": "https://llvm.googlesource.com/llvm-project/+/60e41d241c3a4ba63e777986688ec75cc0612d0f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c058ebda67ed85d3b45140bd25d504e1f7400441"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c058ebda67ed85d3b45140bd25d504e1f7400441",
"title": "c058ebda67ed85d3b45140bd25d504e1f7400441",
"url": "https://llvm.googlesource.com/llvm-project/+/c058ebda67ed85d3b45140bd25d504e1f7400441"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "47981627ddb5bfb49e383474fb1db0c95a2e3b86"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@47981627ddb5bfb49e383474fb1db0c95a2e3b86",
"title": "47981627ddb5bfb49e383474fb1db0c95a2e3b86",
"url": "https://llvm.googlesource.com/llvm-project/+/47981627ddb5bfb49e383474fb1db0c95a2e3b86"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "63d866e04b86f2bf18206f02acf4f25f7be44111"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@63d866e04b86f2bf18206f02acf4f25f7be44111",
"title": "63d866e04b86f2bf18206f02acf4f25f7be44111",
"url": "https://llvm.googlesource.com/llvm-project/+/63d866e04b86f2bf18206f02acf4f25f7be44111"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "aaf23f0887969130fbbfedc2b525921c1c7b687c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@aaf23f0887969130fbbfedc2b525921c1c7b687c",
"title": "aaf23f0887969130fbbfedc2b525921c1c7b687c",
"url": "https://llvm.googlesource.com/llvm-project/+/aaf23f0887969130fbbfedc2b525921c1c7b687c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "dcfb904473dab6c45855b6ab364521124beb5ce1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@dcfb904473dab6c45855b6ab364521124beb5ce1",
"title": "dcfb904473dab6c45855b6ab364521124beb5ce1",
"url": "https://llvm.googlesource.com/llvm-project/+/dcfb904473dab6c45855b6ab364521124beb5ce1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "047ddbf263f6e64c6ec1ef148596661d28544e3a"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@047ddbf263f6e64c6ec1ef148596661d28544e3a",
"title": "047ddbf263f6e64c6ec1ef148596661d28544e3a",
"url": "https://llvm.googlesource.com/llvm-project/+/047ddbf263f6e64c6ec1ef148596661d28544e3a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0c1acc98140c642ff87c1a759bda3ddfaaf23964"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0c1acc98140c642ff87c1a759bda3ddfaaf23964",
"title": "0c1acc98140c642ff87c1a759bda3ddfaaf23964",
"url": "https://llvm.googlesource.com/llvm-project/+/0c1acc98140c642ff87c1a759bda3ddfaaf23964"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "5d85d54feb4e17fa449fa9d9963aabac8b403d7b"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@5d85d54feb4e17fa449fa9d9963aabac8b403d7b",
"title": "5d85d54feb4e17fa449fa9d9963aabac8b403d7b",
"url": "https://llvm.googlesource.com/llvm-project/+/5d85d54feb4e17fa449fa9d9963aabac8b403d7b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "0dbc1e2dff5a4ff0bdc05b310bd93d1eff9bae23"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@0dbc1e2dff5a4ff0bdc05b310bd93d1eff9bae23",
"title": "0dbc1e2dff5a4ff0bdc05b310bd93d1eff9bae23",
"url": "https://llvm.googlesource.com/llvm-project/+/0dbc1e2dff5a4ff0bdc05b310bd93d1eff9bae23"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "2cf71fcb9751a347f39035739f8865ef7a53ee68"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@2cf71fcb9751a347f39035739f8865ef7a53ee68",
"title": "2cf71fcb9751a347f39035739f8865ef7a53ee68",
"url": "https://llvm.googlesource.com/llvm-project/+/2cf71fcb9751a347f39035739f8865ef7a53ee68"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2cf71fcb9751a347f39035739f8865ef7a53ee68",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8867911084235116320"
},
{
"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"
}
}
[12:59:42.964] Scheduled build:
{
"id": "8702481021836332689",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-28T12:59:42.669104838Z",
"updateTime": "2025-09-28T12:59:42.669104838Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2cf71fcb9751a347f39035739f8865ef7a53ee68",
"ref": "refs/heads/main"
}
},
"canary": true
}
[12:59:42.965] Task URL: https://cr-buildbucket.appspot.com/build/8702481021836332689
[12:59:42.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:2:0) after 9m13s
[12:59:44.358] Received PubSub notification, asking Buildbucket for the build status
[12:59:44.374] Build status: SCHEDULED
[12:59:59.963] Received PubSub notification, asking Buildbucket for the build status
[12:59:59.983] Build status: STARTED
[13:08:55.980] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:2:0)
[13:08:55.993] Build status: STARTED
[13:08:55.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:5:0) after 2m58s
[13:11:54.196] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:5:0)
[13:11:54.215] Build status: STARTED
[13:11:54.216] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:6:0) after 9m43s
[13:21:37.232] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:6:0)
[13:21:37.253] Build status: STARTED
[13:21:37.253] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:7:0) after 5m41s
[13:27:18.269] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:7:0)
[13:27:18.312] Build status: STARTED
[13:27:18.312] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:8:0) after 9m30s
[13:36:48.329] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:8:0)
[13:36:48.352] Build status: STARTED
[13:36:48.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:9:0) after 3m55s
[13:40:43.488] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:9:0)
[13:40:43.509] Build status: STARTED
[13:40:43.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:10:0) after 3m26s
[13:44:09.567] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:10:0)
[13:44:09.586] Build status: STARTED
[13:44:09.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:11:0) after 8m40s
[13:52:49.580] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:11:0)
[13:52:49.597] Build status: STARTED
[13:52:49.597] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:12:0) after 1m42s
[13:54:31.730] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:12:0)
[13:54:31.846] Build status: STARTED
[13:54:31.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:13:0) after 4m43s
[13:59:14.864] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:13:0)
[13:59:14.883] Build status: STARTED
[13:59:14.883] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:14:0) after 4m51s
[14:04:06.021] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:14:0)
[14:04:06.044] Build status: STARTED
[14:04:06.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:15:0) after 4m17s
[14:08:23.127] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:15:0)
[14:08:23.154] Build status: STARTED
[14:08:23.154] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:16:0) after 2m0s
[14:10:23.251] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:16:0)
[14:10:23.267] Build status: STARTED
[14:10:23.267] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:17:0) after 2m55s
[14:13:18.284] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:17:0)
[14:13:18.302] Build status: STARTED
[14:13:18.302] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:18:0) after 4m6s
[14:17:24.296] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:18:0)
[14:17:24.314] Build status: STARTED
[14:17:24.315] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:19:0) after 6m7s
[14:23:31.514] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:19:0)
[14:23:31.531] Build status: STARTED
[14:23:31.531] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:20:0) after 1m51s
[14:25:22.546] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:20:0)
[14:25:22.614] Build status: STARTED
[14:25:22.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:21:0) after 3m42s
[14:29:04.631] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:21:0)
[14:29:04.748] Build status: STARTED
[14:29:04.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:22:0) after 3m19s
[14:32:23.761] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:22:0)
[14:32:23.778] Build status: STARTED
[14:32:23.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:23:0) after 9m0s
[14:41:23.797] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:23:0)
[14:41:23.816] Build status: STARTED
[14:41:23.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:24:0) after 4m12s
[14:45:35.834] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:24:0)
[14:45:35.848] Build status: STARTED
[14:45:35.848] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:25:0) after 5m23s
[14:50:58.849] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:25:0)
[14:50:58.920] Build status: STARTED
[14:50:58.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:26:0) after 3m4s
[14:54:03.075] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:26:0)
[14:54:03.146] Build status: STARTED
[14:54:03.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:27:0) after 9m41s
[15:03:44.189] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:27:0)
[15:03:44.207] Build status: STARTED
[15:03:44.207] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:28:0) after 4m34s
[15:08:18.322] Handling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:28:0)
[15:08:18.340] Build status: STARTED
[15:08:18.340] Scheduling timer "check-buildbucket-build-status" (fuchsia/toolchain.ci-clang-mac-x64-40be1bb5:8867911084235116320:29:0) after 4m58s
[15:12:39.165] Received PubSub notification, asking Buildbucket for the build status
[15:12:39.181] Build:
{
"id": "8702481021836332689",
"builder": {
"project": "fuchsia",
"bucket": "toolchain.ci",
"builder": "clang-mac-x64"
},
"createdBy": "project:fuchsia",
"createTime": "2025-09-28T12:59:42.669104838Z",
"startTime": "2025-09-28T12:59:59.505453746Z",
"endTime": "2025-09-28T15:12:37.724384826Z",
"updateTime": "2025-09-28T15:12:37.724384826Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "2cf71fcb9751a347f39035739f8865ef7a53ee68",
"ref": "refs/heads/main"
}
},
"canary": true
}
[15:12:39.181] Invocation finished in 2h12m58.182691912s with status SUCCEEDED