[23:10:24.488] New invocation is queued and will start shortly
[23:10:25.584] Starting the invocation (attempt 1)
[23:10:25.611] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ba5afda60e29768d22788397941b675cb0c15acd"
[23:10:25.611] Popped gitiles commit info from properties and tags
[23:10:25.611] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:10:25.611] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:10:25.611] Buildbucket request:
{
"requestId": "8881462205726188240",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ba5afda60e29768d22788397941b675cb0c15acd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8881462205726188240"
},
{
"key": "scheduler_job_id",
"value": "chromium/Linux Viz"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:10:26.346] Scheduled build:
{
"id": "8716032143794287985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 45912,
"createdBy": "project:chromium",
"createTime": "2025-05-01T23:10:25.700585942Z",
"updateTime": "2025-05-01T23:10:25.700585942Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ba5afda60e29768d22788397941b675cb0c15acd",
"ref": "refs/heads/main"
}
}
}
[23:10:26.346] Task URL: https://cr-buildbucket.appspot.com/build/8716032143794287985
[23:10:26.347] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:2:0) after 5m1s
[23:10:27.247] Received PubSub notification, asking Buildbucket for the build status
[23:10:27.264] Build status: SCHEDULED
[23:15:27.373] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:2:0)
[23:15:27.396] Build status: SCHEDULED
[23:15:27.396] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:4:0) after 8m50s
[23:17:10.639] Received PubSub notification, asking Buildbucket for the build status
[23:17:10.698] Build status: STARTED
[23:24:17.468] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:4:0)
[23:24:17.489] Build status: STARTED
[23:24:17.489] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:6:0) after 9m56s
[23:34:13.493] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:6:0)
[23:34:13.516] Build status: STARTED
[23:34:13.516] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:7:0) after 1m8s
[23:35:21.596] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:7:0)
[23:35:21.623] Build status: STARTED
[23:35:21.623] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:8:0) after 1m7s
[23:36:28.650] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:8:0)
[23:36:28.674] Build status: STARTED
[23:36:28.674] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:9:0) after 4m14s
[23:40:42.728] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:9:0)
[23:40:42.754] Build status: STARTED
[23:40:42.755] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:10:0) after 2m56s
[23:43:38.814] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:10:0)
[23:43:38.876] Build status: STARTED
[23:43:38.876] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:11:0) after 1m46s
[23:45:24.888] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:11:0)
[23:45:24.969] Build status: STARTED
[23:45:24.969] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:12:0) after 7m30s
[23:52:54.996] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:12:0)
[23:52:55.026] Build status: STARTED
[23:52:55.026] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:13:0) after 3m13s
[23:56:08.095] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:13:0)
[23:56:08.113] Build status: STARTED
[23:56:08.113] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:14:0) after 4m21s
[00:00:29.171] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:14:0)
[00:00:29.189] Build status: STARTED
[00:00:29.189] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:15:0) after 6m37s
[00:07:06.256] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:15:0)
[00:07:06.271] Build status: STARTED
[00:07:06.271] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:16:0) after 4m3s
[00:11:09.298] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:16:0)
[00:11:09.345] Build status: STARTED
[00:11:09.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:17:0) after 7m45s
[00:18:54.395] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:17:0)
[00:18:54.424] Build status: STARTED
[00:18:54.424] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:18:0) after 9m2s
[00:27:56.479] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:18:0)
[00:27:56.500] Build status: STARTED
[00:27:56.500] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:19:0) after 2m6s
[00:30:02.564] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:19:0)
[00:30:02.708] Build status: STARTED
[00:30:02.708] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:20:0) after 3m28s
[00:33:30.782] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:20:0)
[00:33:30.799] Build status: STARTED
[00:33:30.799] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:21:0) after 4m18s
[00:37:48.913] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:21:0)
[00:37:48.965] Build status: STARTED
[00:37:48.965] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:22:0) after 1m0s
[00:38:48.992] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:22:0)
[00:38:49.044] Build status: STARTED
[00:38:49.045] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:23:0) after 1m57s
[00:40:46.079] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:23:0)
[00:40:46.098] Build status: STARTED
[00:40:46.098] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:24:0) after 8m45s
[00:49:31.127] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:24:0)
[00:49:31.145] Build status: STARTED
[00:49:31.145] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:25:0) after 9m23s
[00:58:54.174] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:25:0)
[00:58:54.198] Build status: STARTED
[00:58:54.198] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:26:0) after 1m42s
[01:00:36.288] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:26:0)
[01:00:36.303] Build status: STARTED
[01:00:36.303] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:27:0) after 2m53s
[01:03:29.329] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:27:0)
[01:03:29.345] Build status: STARTED
[01:03:29.345] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:28:0) after 6m9s
[01:09:38.371] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:28:0)
[01:09:38.397] Build status: STARTED
[01:09:38.398] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:29:0) after 5m14s
[01:14:52.436] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:29:0)
[01:14:52.453] Build status: STARTED
[01:14:52.453] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:30:0) after 8m4s
[01:22:56.588] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:30:0)
[01:22:56.610] Build status: STARTED
[01:22:56.610] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:31:0) after 1m5s
[01:24:01.639] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:31:0)
[01:24:01.655] Build status: STARTED
[01:24:01.655] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:32:0) after 6m30s
[01:30:31.703] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:32:0)
[01:30:31.724] Build status: STARTED
[01:30:31.724] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:33:0) after 2m52s
[01:33:23.783] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:33:0)
[01:33:23.801] Build status: STARTED
[01:33:23.801] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:34:0) after 7m45s
[01:41:08.918] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:34:0)
[01:41:08.932] Build status: STARTED
[01:41:08.932] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:35:0) after 7m52s
[01:49:00.953] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:35:0)
[01:49:00.986] Build status: STARTED
[01:49:00.986] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:36:0) after 1m49s
[01:50:49.994] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:36:0)
[01:50:50.011] Build status: STARTED
[01:50:50.011] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:37:0) after 8m36s
[01:59:26.031] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:37:0)
[01:59:26.052] Build status: STARTED
[01:59:26.052] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:38:0) after 1m41s
[02:01:07.204] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:38:0)
[02:01:07.260] Build status: STARTED
[02:01:07.260] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:39:0) after 1m9s
[02:02:16.283] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:39:0)
[02:02:16.301] Build status: STARTED
[02:02:16.301] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:40:0) after 3m39s
[02:05:55.331] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:40:0)
[02:05:55.348] Build status: STARTED
[02:05:55.348] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:41:0) after 1m25s
[02:07:20.374] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:41:0)
[02:07:20.395] Build status: STARTED
[02:07:20.395] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:42:0) after 6m39s
[02:13:59.419] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:42:0)
[02:13:59.518] Build status: STARTED
[02:13:59.518] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:43:0) after 6m46s
[02:20:45.621] Handling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:43:0)
[02:20:45.640] Build status: STARTED
[02:20:45.640] Scheduling timer "check-buildbucket-build-status" (chromium/Linux Viz:8881462205726188240:44:0) after 5m27s
[02:20:46.795] Received PubSub notification, asking Buildbucket for the build status
[02:20:46.813] Build:
{
"id": "8716032143794287985",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Linux Viz"
},
"number": 45912,
"createdBy": "project:chromium",
"createTime": "2025-05-01T23:10:25.700585942Z",
"startTime": "2025-05-01T23:17:09.588218897Z",
"endTime": "2025-05-02T02:20:46.485070508Z",
"updateTime": "2025-05-02T02:20:46.485070508Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ba5afda60e29768d22788397941b675cb0c15acd",
"ref": "refs/heads/main"
}
}
}
[02:20:46.813] Invocation finished in 3h10m22.339140973s with status FAILED