[10:21:24.868] New invocation is queued and will start shortly
[10:21:26.103] Starting the invocation (attempt 1)
[10:21:26.134] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/984a35b0825d560017e4d9d548488fe6d5ad9f35"
[10:21:26.134] Popped gitiles commit info from properties and tags
[10:21:26.134] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:21:26.135] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:21:26.135] Buildbucket request:
{
"requestId": "8868102235596214336",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-no-external-ip"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "984a35b0825d560017e4d9d548488fe6d5ad9f35",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868102235596214336"
},
{
"key": "scheduler_job_id",
"value": "chromium/linux-rel-no-external-ip"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[10:21:26.791] Scheduled build:
{
"id": "8702672173500429425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-no-external-ip"
},
"number": 14991,
"createdBy": "project:chromium",
"createTime": "2025-09-26T10:21:26.237990327Z",
"updateTime": "2025-09-26T10:21:26.237990327Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "984a35b0825d560017e4d9d548488fe6d5ad9f35",
"ref": "refs/heads/main"
}
}
}
[10:21:26.791] Task URL: https://cr-buildbucket.appspot.com/build/8702672173500429425
[10:21:26.791] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:2:0) after 5m25s
[10:21:27.732] Received PubSub notification, asking Buildbucket for the build status
[10:21:27.752] Build status: SCHEDULED
[10:22:04.963] Received PubSub notification, asking Buildbucket for the build status
[10:22:05.018] Build status: STARTED
[10:26:51.806] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:2:0)
[10:26:51.832] Build status: STARTED
[10:26:51.832] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:5:0) after 7m34s
[10:34:25.889] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:5:0)
[10:34:25.904] Build status: STARTED
[10:34:25.904] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:6:0) after 3m4s
[10:37:29.959] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:6:0)
[10:37:29.980] Build status: STARTED
[10:37:29.980] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:7:0) after 1m29s
[10:38:59.169] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:7:0)
[10:38:59.199] Build status: STARTED
[10:38:59.199] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:8:0) after 1m20s
[10:40:19.214] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:8:0)
[10:40:19.230] Build status: STARTED
[10:40:19.230] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:9:0) after 5m20s
[10:45:39.248] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:9:0)
[10:45:39.272] Build status: STARTED
[10:45:39.272] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:10:0) after 7m41s
[10:53:20.289] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:10:0)
[10:53:20.411] Build status: STARTED
[10:53:20.411] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:11:0) after 9m56s
[11:03:16.382] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:11:0)
[11:03:16.402] Build status: STARTED
[11:03:16.402] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:12:0) after 4m3s
[11:07:19.532] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:12:0)
[11:07:19.560] Build status: STARTED
[11:07:19.560] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:13:0) after 5m19s
[11:12:38.652] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:13:0)
[11:12:38.678] Build status: STARTED
[11:12:38.678] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:14:0) after 4m7s
[11:16:45.690] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:14:0)
[11:16:45.781] Build status: STARTED
[11:16:45.781] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:15:0) after 7m51s
[11:24:36.855] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:15:0)
[11:24:36.882] Build status: STARTED
[11:24:36.882] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:16:0) after 9m36s
[11:34:12.900] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:16:0)
[11:34:12.976] Build status: STARTED
[11:34:12.976] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:17:0) after 1m45s
[11:35:57.994] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:17:0)
[11:35:58.025] Build status: STARTED
[11:35:58.025] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:18:0) after 2m47s
[11:38:45.011] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:18:0)
[11:38:45.069] Build status: STARTED
[11:38:45.069] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:19:0) after 2m51s
[11:41:36.077] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:19:0)
[11:41:36.097] Build status: STARTED
[11:41:36.097] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:20:0) after 9m25s
[11:51:01.148] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:20:0)
[11:51:01.183] Build status: STARTED
[11:51:01.183] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:21:0) after 1m9s
[11:52:10.215] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:21:0)
[11:52:10.243] Build status: STARTED
[11:52:10.243] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:22:0) after 9m28s
[12:01:38.263] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:22:0)
[12:01:38.279] Build status: STARTED
[12:01:38.279] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:23:0) after 2m53s
[12:04:31.296] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:23:0)
[12:04:31.315] Build status: STARTED
[12:04:31.315] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:24:0) after 4m44s
[12:09:15.332] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:24:0)
[12:09:15.351] Build status: STARTED
[12:09:15.351] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:25:0) after 7m54s
[12:17:09.356] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:25:0)
[12:17:09.372] Build status: STARTED
[12:17:09.372] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:26:0) after 1m53s
[12:19:02.425] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:26:0)
[12:19:02.444] Build status: STARTED
[12:19:02.444] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:27:0) after 9m26s
[12:28:28.497] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:27:0)
[12:28:28.514] Build status: STARTED
[12:28:28.514] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:28:0) after 1m44s
[12:30:12.531] Handling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:28:0)
[12:30:12.593] Build status: STARTED
[12:30:12.593] Scheduling timer "check-buildbucket-build-status" (chromium/linux-rel-no-external-ip:8868102235596214336:29:0) after 5m34s
[12:32:04.325] Received PubSub notification, asking Buildbucket for the build status
[12:32:04.351] Build:
{
"id": "8702672173500429425",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "linux-rel-no-external-ip"
},
"number": 14991,
"createdBy": "project:chromium",
"createTime": "2025-09-26T10:21:26.237990327Z",
"startTime": "2025-09-26T10:22:04.389407699Z",
"endTime": "2025-09-26T12:31:07.713668828Z",
"updateTime": "2025-09-26T12:31:07.713668828Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "984a35b0825d560017e4d9d548488fe6d5ad9f35",
"ref": "refs/heads/main"
}
}
}
[12:32:04.351] Invocation finished in 2h10m39.495174134s with status FAILED