[14:45:19.023] New invocation is queued and will start shortly
[14:45:20.269] Starting the invocation (attempt 1)
[14:45:20.301] Popped buildset tag "buildset:commit/gitiles/llvm.googlesource.com/llvm-project/+/c21a3776c94eef46ea43192c6d0b8df6258b6489"
[14:45:20.301] Popped gitiles commit info from properties and tags
[14:45:20.302] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:45:20.302] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:45:20.302] Buildbucket request:
{
"requestId": "8892909201075030048",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8892909201075030048",
"job": "fuchsia/clang-prod-unbounded-trigger-40be1bb5",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "698bb5f239f50e8217cbec1d19bf8e0bba8c5d11"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@698bb5f239f50e8217cbec1d19bf8e0bba8c5d11",
"title": "698bb5f239f50e8217cbec1d19bf8e0bba8c5d11",
"url": "https://llvm.googlesource.com/llvm-project/+/698bb5f239f50e8217cbec1d19bf8e0bba8c5d11"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "1b476ecdcf4b544af1436341fc923c0b73793cbe"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@1b476ecdcf4b544af1436341fc923c0b73793cbe",
"title": "1b476ecdcf4b544af1436341fc923c0b73793cbe",
"url": "https://llvm.googlesource.com/llvm-project/+/1b476ecdcf4b544af1436341fc923c0b73793cbe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "889215a30ed60474e573f9632d1fa362dfa1b04e"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@889215a30ed60474e573f9632d1fa362dfa1b04e",
"title": "889215a30ed60474e573f9632d1fa362dfa1b04e",
"url": "https://llvm.googlesource.com/llvm-project/+/889215a30ed60474e573f9632d1fa362dfa1b04e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://llvm.googlesource.com/llvm-project",
"revision": "c21a3776c94eef46ea43192c6d0b8df6258b6489"
},
"id": "https://llvm.googlesource.com/llvm-project/+/refs/heads/main@c21a3776c94eef46ea43192c6d0b8df6258b6489",
"title": "c21a3776c94eef46ea43192c6d0b8df6258b6489",
"url": "https://llvm.googlesource.com/llvm-project/+/c21a3776c94eef46ea43192c6d0b8df6258b6489"
}
]
}
},
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c21a3776c94eef46ea43192c6d0b8df6258b6489",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892909201075030048"
},
{
"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"
}
}
[14:45:20.681] Scheduled build:
{
"id": "8727479138989812097",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-26T14:45:20.382522233Z",
"updateTime": "2024-12-26T14:45:20.382522233Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c21a3776c94eef46ea43192c6d0b8df6258b6489",
"ref": "refs/heads/main"
}
}
}
[14:45:20.681] Task URL: https://cr-buildbucket.appspot.com/build/8727479138989812097
[14:45:20.681] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:2:0) after 5m25s
[14:45:41.315] Received PubSub notification, asking Buildbucket for the build status
[14:45:41.342] Build status: STARTED
[14:45:44.757] Received PubSub notification, asking Buildbucket for the build status
[14:45:44.787] Build status: STARTED
[14:50:45.861] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:2:0)
[14:50:45.894] Build status: STARTED
[14:50:45.894] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:5:0) after 1m40s
[14:52:25.923] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:5:0)
[14:52:25.977] Build status: STARTED
[14:52:25.977] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:6:0) after 4m53s
[14:57:18.993] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:6:0)
[14:57:19.036] Build status: STARTED
[14:57:19.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:7:0) after 1m50s
[14:59:09.116] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:7:0)
[14:59:09.153] Build status: STARTED
[14:59:09.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:8:0) after 1m38s
[15:00:47.168] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:8:0)
[15:00:47.232] Build status: STARTED
[15:00:47.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:9:0) after 2m3s
[15:02:50.286] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:9:0)
[15:02:50.306] Build status: STARTED
[15:02:50.306] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:10:0) after 2m14s
[15:05:04.336] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:10:0)
[15:05:04.432] Build status: STARTED
[15:05:04.432] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:11:0) after 1m33s
[15:06:37.453] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:11:0)
[15:06:37.477] Build status: STARTED
[15:06:37.477] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:12:0) after 4m50s
[15:11:27.536] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:12:0)
[15:11:27.570] Build status: STARTED
[15:11:27.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:13:0) after 8m54s
[15:20:21.585] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:13:0)
[15:20:21.604] Build status: STARTED
[15:20:21.604] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:14:0) after 1m54s
[15:22:15.721] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:14:0)
[15:22:15.745] Build status: STARTED
[15:22:15.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:15:0) after 5m15s
[15:27:30.765] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:15:0)
[15:27:30.792] Build status: STARTED
[15:27:30.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:16:0) after 8m21s
[15:35:51.936] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:16:0)
[15:35:51.971] Build status: STARTED
[15:35:51.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:17:0) after 7m42s
[15:43:34.089] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:17:0)
[15:43:34.114] Build status: STARTED
[15:43:34.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:18:0) after 6m47s
[15:50:21.234] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:18:0)
[15:50:21.270] Build status: STARTED
[15:50:21.270] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:19:0) after 9m6s
[15:59:27.369] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:19:0)
[15:59:27.401] Build status: STARTED
[15:59:27.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:20:0) after 6m22s
[16:05:49.606] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:20:0)
[16:05:49.624] Build status: STARTED
[16:05:49.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:21:0) after 7m43s
[16:13:32.609] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:21:0)
[16:13:32.632] Build status: STARTED
[16:13:32.632] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:22:0) after 4m15s
[16:17:47.656] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:22:0)
[16:17:47.674] Build status: STARTED
[16:17:47.674] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:23:0) after 5m25s
[16:23:12.700] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:23:0)
[16:23:12.718] Build status: STARTED
[16:23:12.718] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:24:0) after 4m23s
[16:27:35.736] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:24:0)
[16:27:35.763] Build status: STARTED
[16:27:35.763] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:25:0) after 2m57s
[16:30:32.779] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:25:0)
[16:30:32.796] Build status: STARTED
[16:30:32.796] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:26:0) after 1m13s
[16:31:45.835] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:26:0)
[16:31:45.861] Build status: STARTED
[16:31:45.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:27:0) after 4m36s
[16:36:22.071] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:27:0)
[16:36:22.098] Build status: STARTED
[16:36:22.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:28:0) after 6m16s
[16:42:38.116] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:28:0)
[16:42:38.150] Build status: STARTED
[16:42:38.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:29:0) after 5m53s
[16:48:31.175] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:29:0)
[16:48:31.198] Build status: STARTED
[16:48:31.198] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:30:0) after 5m12s
[16:53:43.299] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:30:0)
[16:53:43.318] Build status: STARTED
[16:53:43.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:31:0) after 8m7s
[17:01:50.361] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:31:0)
[17:01:50.376] Build status: STARTED
[17:01:50.376] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:32:0) after 9m41s
[17:11:31.533] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:32:0)
[17:11:31.570] Build status: STARTED
[17:11:31.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:33:0) after 7m22s
[17:18:53.697] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:33:0)
[17:18:53.776] Build status: STARTED
[17:18:53.776] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:34:0) after 3m37s
[17:22:30.815] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:34:0)
[17:22:30.838] Build status: STARTED
[17:22:30.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:35:0) after 4m23s
[17:26:53.918] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:35:0)
[17:26:53.945] Build status: STARTED
[17:26:53.945] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:36:0) after 1m58s
[17:28:52.048] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:36:0)
[17:28:52.066] Build status: STARTED
[17:28:52.066] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:37:0) after 7m51s
[17:36:43.446] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:37:0)
[17:36:43.515] Build status: STARTED
[17:36:43.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:38:0) after 5m32s
[17:42:15.635] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:38:0)
[17:42:15.797] Build status: STARTED
[17:42:15.797] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:39:0) after 6m54s
[17:49:09.915] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:39:0)
[17:49:09.931] Build status: STARTED
[17:49:09.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:40:0) after 4m7s
[17:53:16.985] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:40:0)
[17:53:17.005] Build status: STARTED
[17:53:17.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:41:0) after 9m29s
[18:02:46.033] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:41:0)
[18:02:46.078] Build status: STARTED
[18:02:46.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:42:0) after 7m47s
[18:10:33.111] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:42:0)
[18:10:33.133] Build status: STARTED
[18:10:33.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:43:0) after 1m22s
[18:11:55.294] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:43:0)
[18:11:55.311] Build status: STARTED
[18:11:55.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:44:0) after 5m41s
[18:17:36.340] Handling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:44:0)
[18:17:36.369] Build status: STARTED
[18:17:36.369] Scheduling timer "check-buildbucket-build-status" (fuchsia/clang-prod-unbounded-trigger-40be1bb5:8892909201075030048:45:0) after 9m6s
[18:25:19.452] Received PubSub notification, asking Buildbucket for the build status
[18:25:19.468] Build:
{
"id": "8727479138989812097",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "clang-prod-unbounded-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2024-12-26T14:45:20.382522233Z",
"startTime": "2024-12-26T14:45:25.646568667Z",
"endTime": "2024-12-26T18:25:19.109702348Z",
"updateTime": "2024-12-26T18:25:19.109702348Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "llvm.googlesource.com",
"project": "llvm-project",
"id": "c21a3776c94eef46ea43192c6d0b8df6258b6489",
"ref": "refs/heads/main"
}
}
}
[18:25:19.468] Invocation finished in 3h40m0.458175188s with status SUCCEEDED