[08:19:04.946] New invocation is queued and will start shortly
[08:19:06.153] Starting the invocation (attempt 1)
[08:19:06.234] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/9d5ceccbd909398babd1ab71d62b0b708bb066c0"
[08:19:06.234] Popped gitiles commit info from properties and tags
[08:19:06.234] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:19:06.236] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:19:06.236] Buildbucket request:
{
"requestId": "8899275288502854944",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8899275288502854944",
"job": "fuchsia/clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "927af63fddb8e34f23b2974f812156767988ec5f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@927af63fddb8e34f23b2974f812156767988ec5f",
"title": "927af63fddb8e34f23b2974f812156767988ec5f",
"url": "https://llvm.googlesource.com/llvm-project/+/927af63fddb8e34f23b2974f812156767988ec5f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3142dff70401086a14ee9ae3428f65f5dfa6a2e6"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3142dff70401086a14ee9ae3428f65f5dfa6a2e6",
"title": "3142dff70401086a14ee9ae3428f65f5dfa6a2e6",
"url": "https://llvm.googlesource.com/llvm-project/+/3142dff70401086a14ee9ae3428f65f5dfa6a2e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "23d4fe6c5c52e054bbed75e78104f59869337356"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@23d4fe6c5c52e054bbed75e78104f59869337356",
"title": "23d4fe6c5c52e054bbed75e78104f59869337356",
"url": "https://llvm.googlesource.com/llvm-project/+/23d4fe6c5c52e054bbed75e78104f59869337356"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "3ae6b57671744b4fe4dd76769cce0745a0f5bc31"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@3ae6b57671744b4fe4dd76769cce0745a0f5bc31",
"title": "3ae6b57671744b4fe4dd76769cce0745a0f5bc31",
"url": "https://llvm.googlesource.com/llvm-project/+/3ae6b57671744b4fe4dd76769cce0745a0f5bc31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "255a99c29f9fa1a89b03a85a3a73d6f44d03c6c1"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@255a99c29f9fa1a89b03a85a3a73d6f44d03c6c1",
"title": "255a99c29f9fa1a89b03a85a3a73d6f44d03c6c1",
"url": "https://llvm.googlesource.com/llvm-project/+/255a99c29f9fa1a89b03a85a3a73d6f44d03c6c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "267be4a7f4ac69cfd1bec5223554bbe400c5636c"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@267be4a7f4ac69cfd1bec5223554bbe400c5636c",
"title": "267be4a7f4ac69cfd1bec5223554bbe400c5636c",
"url": "https://llvm.googlesource.com/llvm-project/+/267be4a7f4ac69cfd1bec5223554bbe400c5636c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1cc5290a30a0d6dffeb2e0f475558fcf3ded8e1f"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1cc5290a30a0d6dffeb2e0f475558fcf3ded8e1f",
"title": "1cc5290a30a0d6dffeb2e0f475558fcf3ded8e1f",
"url": "https://llvm.googlesource.com/llvm-project/+/1cc5290a30a0d6dffeb2e0f475558fcf3ded8e1f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "cb9bacf57d5c58eba28a76fd07ea2d4f9a0da847"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@cb9bacf57d5c58eba28a76fd07ea2d4f9a0da847",
"title": "cb9bacf57d5c58eba28a76fd07ea2d4f9a0da847",
"url": "https://llvm.googlesource.com/llvm-project/+/cb9bacf57d5c58eba28a76fd07ea2d4f9a0da847"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "9d5ceccbd909398babd1ab71d62b0b708bb066c0"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@9d5ceccbd909398babd1ab71d62b0b708bb066c0",
"title": "9d5ceccbd909398babd1ab71d62b0b708bb066c0",
"url": "https://llvm.googlesource.com/llvm-project/+/9d5ceccbd909398babd1ab71d62b0b708bb066c0"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "9d5ceccbd909398babd1ab71d62b0b708bb066c0",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8899275288502854944"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/clang-prod-unbounded-trigger-40be1bb5"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[08:19:06.705] Scheduled build:
{
"id": "8733845226419381121",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-17T08:19:06.304400259Z",
"updateTime": "2024-10-17T08:19:06.304400259Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "9d5ceccbd909398babd1ab71d62b0b708bb066c0",
"ref": "refs/heads/main"
}
}
}
[08:19:06.705] Task URL: https://cr-buildbucket.appspot.com/build/8733845226419381121
[08:19:06.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:2:0) after 9m28s
[08:19:07.619] Received PubSub notification, asking Buildbucket for the build status
[08:19:07.641] Build status: SCHEDULED
[08:19:13.659] Received PubSub notification, asking Buildbucket for the build status
[08:19:13.683] Build status: STARTED
[08:28:34.774] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:2:0)
[08:28:34.794] Build status: STARTED
[08:28:34.794] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:5:0) after 8m8s
[08:36:42.797] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:5:0)
[08:36:42.816] Build status: STARTED
[08:36:42.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:6:0) after 2m18s
[08:39:00.828] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:6:0)
[08:39:00.852] Build status: STARTED
[08:39:00.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:7:0) after 5m49s
[08:44:50.025] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:7:0)
[08:44:50.091] Build status: STARTED
[08:44:50.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:8:0) after 5m38s
[08:50:28.135] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:8:0)
[08:50:28.159] Build status: STARTED
[08:50:28.159] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:9:0) after 9m24s
[08:59:52.151] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:9:0)
[08:59:52.169] Build status: STARTED
[08:59:52.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:10:0) after 8m20s
[09:08:12.488] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:10:0)
[09:08:12.509] Build status: STARTED
[09:08:12.509] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:11:0) after 7m14s
[09:15:26.588] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:11:0)
[09:15:26.604] Build status: STARTED
[09:15:26.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:12:0) after 6m37s
[09:22:03.884] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:12:0)
[09:22:03.938] Build status: STARTED
[09:22:03.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:13:0) after 4m50s
[09:26:54.215] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:13:0)
[09:26:54.238] Build status: STARTED
[09:26:54.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:14:0) after 3m43s
[09:30:37.525] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:14:0)
[09:30:37.545] Build status: STARTED
[09:30:37.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:15:0) after 6m6s
[09:36:43.670] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:15:0)
[09:36:43.689] Build status: STARTED
[09:36:43.689] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:16:0) after 2m31s
[09:39:14.744] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:16:0)
[09:39:14.933] Build status: STARTED
[09:39:14.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:17:0) after 3m40s
[09:42:55.167] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:17:0)
[09:42:55.194] Build status: STARTED
[09:42:55.194] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:18:0) after 2m31s
[09:45:26.212] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:18:0)
[09:45:26.328] Build status: STARTED
[09:45:26.328] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:19:0) after 4m30s
[09:49:56.334] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:19:0)
[09:49:56.352] Build status: STARTED
[09:49:56.352] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:20:0) after 8m53s
[09:58:49.675] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:20:0)
[09:58:49.709] Build status: STARTED
[09:58:49.709] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:21:0) after 9m40s
[10:08:29.728] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:21:0)
[10:08:29.748] Build status: STARTED
[10:08:29.748] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:22:0) after 8m14s
[10:16:43.868] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:22:0)
[10:16:43.889] Build status: STARTED
[10:16:43.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:23:0) after 7m47s
[10:24:31.054] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:23:0)
[10:24:31.076] Build status: STARTED
[10:24:31.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:24:0) after 5m2s
[10:29:33.195] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:24:0)
[10:29:33.215] Build status: STARTED
[10:29:33.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:25:0) after 7m20s
[10:36:53.458] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:25:0)
[10:36:53.476] Build status: STARTED
[10:36:53.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:26:0) after 9m56s
[10:46:49.578] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:26:0)
[10:46:49.594] Build status: STARTED
[10:46:49.594] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:27:0) after 5m9s
[10:51:58.612] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:27:0)
[10:51:58.773] Build status: STARTED
[10:51:58.773] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:28:0) after 7m47s
[10:59:45.855] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:28:0)
[10:59:45.877] Build status: STARTED
[10:59:45.877] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:29:0) after 1m8s
[11:00:53.896] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:29:0)
[11:00:53.914] Build status: STARTED
[11:00:53.914] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:30:0) after 3m17s
[11:04:11.045] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:30:0)
[11:04:11.113] Build status: STARTED
[11:04:11.113] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:31:0) after 8m50s
[11:13:01.228] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:31:0)
[11:13:01.264] Build status: STARTED
[11:13:01.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:32:0) after 9m18s
[11:22:19.507] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:32:0)
[11:22:19.526] Build status: STARTED
[11:22:19.526] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:33:0) after 1m20s
[11:23:39.545] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:33:0)
[11:23:39.617] Build status: STARTED
[11:23:39.617] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:34:0) after 6m33s
[11:30:12.828] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:34:0)
[11:30:13.046] Build status: STARTED
[11:30:13.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:35:0) after 2m29s
[11:32:42.473] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:35:0)
[11:32:42.493] Build status: STARTED
[11:32:42.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:36:0) after 9m52s
[11:42:34.660] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:36:0)
[11:42:34.713] Build status: STARTED
[11:42:34.713] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:37:0) after 5m16s
[11:47:50.804] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:37:0)
[11:47:50.833] Build status: STARTED
[11:47:50.833] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8899275288502854944:38:0) after 7m24s
[11:52:02.001] Received PubSub notification, asking Buildbucket for the build status
[11:52:02.017] Build:
{
"id": "8733845226419381121",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-17T08:19:06.304400259Z",
"startTime": "2024-10-17T08:19:13.425494153Z",
"endTime": "2024-10-17T11:52:01.562963844Z",
"updateTime": "2024-10-17T11:52:01.562963844Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "9d5ceccbd909398babd1ab71d62b0b708bb066c0",
"ref": "refs/heads/main"
}
}
}
[11:52:02.017] Invocation finished in 3h32m57.083221129s with status SUCCEEDED