[09:54:46.770] New invocation is queued and will start shortly
[09:54:50.981] Starting the invocation (attempt 1)
[09:54:51.004] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02e49ea1cceb59a8f22bb5660f9350d3be067f7b"
[09:54:51.004] Popped gitiles commit info from properties and tags
[09:54:51.004] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:54:51.004] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:54:51.004] Buildbucket request:
{
"requestId": "8849078548371659184",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02e49ea1cceb59a8f22bb5660f9350d3be067f7b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8849078548371659184"
},
{
"key": "scheduler_job_id",
"value": "chromium/CrWinAsan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[09:54:51.487] Scheduled build:
{
"id": "8683648483220260001",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 21168,
"createdBy": "project:chromium",
"createTime": "2026-04-24T09:54:51.060669867Z",
"updateTime": "2026-04-24T09:54:51.060669867Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02e49ea1cceb59a8f22bb5660f9350d3be067f7b",
"ref": "refs/heads/main"
}
}
}
[09:54:51.487] Task URL: https://cr-buildbucket.appspot.com/build/8683648483220260001
[09:54:51.487] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:2:0) after 3m30s
[09:54:51.867] Received PubSub notification, asking Buildbucket for the build status
[09:54:51.886] Build status: SCHEDULED
[09:54:56.059] Received PubSub notification, asking Buildbucket for the build status
[09:54:56.081] Build status: STARTED
[09:58:21.618] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:2:0)
[09:58:21.650] Build status: STARTED
[09:58:21.650] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:5:0) after 8m51s
[10:07:12.664] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:5:0)
[10:07:12.682] Build status: STARTED
[10:07:12.682] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:6:0) after 6m59s
[10:14:11.746] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:6:0)
[10:14:11.819] Build status: STARTED
[10:14:11.819] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:7:0) after 6m28s
[10:20:39.832] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:7:0)
[10:20:39.848] Build status: STARTED
[10:20:39.848] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:8:0) after 8m53s
[10:29:32.861] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:8:0)
[10:29:32.876] Build status: STARTED
[10:29:32.876] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:9:0) after 2m45s
[10:32:17.969] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:9:0)
[10:32:17.995] Build status: STARTED
[10:32:17.995] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:10:0) after 8m13s
[10:40:31.063] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:10:0)
[10:40:31.082] Build status: STARTED
[10:40:31.082] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:11:0) after 2m7s
[10:42:38.093] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:11:0)
[10:42:38.111] Build status: STARTED
[10:42:38.111] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:12:0) after 2m58s
[10:45:36.084] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:12:0)
[10:45:36.102] Build status: STARTED
[10:45:36.102] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:13:0) after 8m48s
[10:54:24.165] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:13:0)
[10:54:24.190] Build status: STARTED
[10:54:24.190] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:14:0) after 9m47s
[11:04:11.254] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:14:0)
[11:04:11.284] Build status: STARTED
[11:04:11.284] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:15:0) after 9m14s
[11:13:25.300] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:15:0)
[11:13:25.333] Build status: STARTED
[11:13:25.333] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:16:0) after 5m2s
[11:18:27.347] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:16:0)
[11:18:27.373] Build status: STARTED
[11:18:27.373] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:17:0) after 1m5s
[11:19:32.385] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:17:0)
[11:19:32.409] Build status: STARTED
[11:19:32.409] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:18:0) after 7m34s
[11:27:06.441] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:18:0)
[11:27:06.494] Build status: STARTED
[11:27:06.494] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:19:0) after 8m13s
[11:35:19.516] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:19:0)
[11:35:19.569] Build status: STARTED
[11:35:19.569] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:20:0) after 3m12s
[11:38:31.640] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:20:0)
[11:38:31.679] Build status: STARTED
[11:38:31.679] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:21:0) after 4m5s
[11:42:36.698] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:21:0)
[11:42:36.714] Build status: STARTED
[11:42:36.715] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:22:0) after 2m30s
[11:45:06.764] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:22:0)
[11:45:06.784] Build status: STARTED
[11:45:06.793] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:23:0) after 6m17s
[11:51:23.929] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:23:0)
[11:51:23.942] Build status: STARTED
[11:51:23.942] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:24:0) after 3m26s
[11:54:50.012] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:24:0)
[11:54:50.066] Build status: STARTED
[11:54:50.066] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:25:0) after 3m51s
[11:58:41.049] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:25:0)
[11:58:41.068] Build status: STARTED
[11:58:41.068] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:26:0) after 4m53s
[12:03:34.098] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:26:0)
[12:03:34.117] Build status: STARTED
[12:03:34.117] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:27:0) after 2m51s
[12:06:25.129] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:27:0)
[12:06:25.157] Build status: STARTED
[12:06:25.157] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:28:0) after 8m46s
[12:15:11.329] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:28:0)
[12:15:11.360] Build status: STARTED
[12:15:11.360] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:29:0) after 6m17s
[12:21:28.325] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:29:0)
[12:21:28.353] Build status: STARTED
[12:21:28.353] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:30:0) after 4m37s
[12:26:05.373] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:30:0)
[12:26:05.439] Build status: STARTED
[12:26:05.439] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:31:0) after 9m33s
[12:35:38.456] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:31:0)
[12:35:38.479] Build status: STARTED
[12:35:38.479] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:32:0) after 2m48s
[12:38:26.496] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:32:0)
[12:38:26.521] Build status: STARTED
[12:38:26.521] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:33:0) after 1m40s
[12:40:06.506] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:33:0)
[12:40:06.528] Build status: STARTED
[12:40:06.528] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:34:0) after 4m15s
[12:44:21.697] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:34:0)
[12:44:21.712] Build status: STARTED
[12:44:21.712] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:35:0) after 3m27s
[12:47:48.829] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:35:0)
[12:47:48.845] Build status: STARTED
[12:47:48.845] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:36:0) after 8m20s
[12:56:08.866] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:36:0)
[12:56:08.888] Build status: STARTED
[12:56:08.889] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:37:0) after 1m50s
[12:57:58.984] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:37:0)
[12:57:59.003] Build status: STARTED
[12:57:59.003] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:38:0) after 9m19s
[13:07:18.022] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:38:0)
[13:07:18.047] Build status: STARTED
[13:07:18.047] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:39:0) after 9m32s
[13:16:50.134] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:39:0)
[13:16:50.153] Build status: STARTED
[13:16:50.153] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:40:0) after 5m22s
[13:22:12.202] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:40:0)
[13:22:12.229] Build status: STARTED
[13:22:12.229] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:41:0) after 1m31s
[13:23:43.356] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:41:0)
[13:23:43.375] Build status: STARTED
[13:23:43.375] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:42:0) after 9m19s
[13:33:02.388] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:42:0)
[13:33:02.416] Build status: STARTED
[13:33:02.416] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:43:0) after 9m58s
[13:43:00.480] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:43:0)
[13:43:00.496] Build status: STARTED
[13:43:00.496] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:44:0) after 9m57s
[13:52:57.497] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:44:0)
[13:52:57.520] Build status: STARTED
[13:52:57.520] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:45:0) after 3m18s
[13:56:15.616] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:45:0)
[13:56:15.672] Build status: STARTED
[13:56:15.672] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:46:0) after 7m58s
[14:04:13.666] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:46:0)
[14:04:13.682] Build status: STARTED
[14:04:13.682] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:47:0) after 8m29s
[14:12:42.730] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:47:0)
[14:12:42.761] Build status: STARTED
[14:12:42.761] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:48:0) after 5m3s
[14:17:45.780] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:48:0)
[14:17:45.795] Build status: STARTED
[14:17:45.795] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:49:0) after 1m15s
[14:19:00.803] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:49:0)
[14:19:00.831] Build status: STARTED
[14:19:00.831] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:50:0) after 3m19s
[14:22:19.862] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:50:0)
[14:22:19.901] Build status: STARTED
[14:22:19.901] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:51:0) after 5m7s
[14:27:26.926] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:51:0)
[14:27:26.945] Build status: STARTED
[14:27:26.945] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:52:0) after 2m21s
[14:29:47.981] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:52:0)
[14:29:48.000] Build status: STARTED
[14:29:48.000] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:53:0) after 9m51s
[14:39:39.094] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:53:0)
[14:39:39.110] Build status: STARTED
[14:39:39.110] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:54:0) after 7m12s
[14:46:51.335] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:54:0)
[14:46:51.353] Build status: STARTED
[14:46:51.353] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:55:0) after 4m35s
[14:51:26.369] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:55:0)
[14:51:26.544] Build status: STARTED
[14:51:26.544] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:56:0) after 1m0s
[14:52:26.670] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:56:0)
[14:52:26.689] Build status: STARTED
[14:52:26.689] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8849078548371659184:57:0) after 9m28s
[14:54:57.642] Received PubSub notification, asking Buildbucket for the build status
[14:54:57.666] Build:
{
"id": "8683648483220260001",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 21168,
"createdBy": "project:chromium",
"createTime": "2026-04-24T09:54:51.060669867Z",
"startTime": "2026-04-24T09:54:55.887074327Z",
"endTime": "2026-04-24T14:54:56.621441621Z",
"updateTime": "2026-04-24T14:54:56.621441621Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02e49ea1cceb59a8f22bb5660f9350d3be067f7b",
"ref": "refs/heads/main"
}
}
}
[14:54:57.666] Invocation finished in 5h0m10.902241852s with status SUCCEEDED