[06:03:43.440] New invocation is queued and will start shortly
[06:03:44.609] Starting the invocation (attempt 1)
[06:03:44.636] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/47dfd5dedef3fca08c136753acecaea51e5b515d"
[06:03:44.636] Popped gitiles commit info from properties and tags
[06:03:44.636] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[06:03:44.636] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[06:03:44.636] Buildbucket request:
{
"requestId": "8833148019038173488",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "47dfd5dedef3fca08c136753acecaea51e5b515d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8833148019038173488"
},
{
"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"
}
}
[06:03:45.156] Scheduled build:
{
"id": "8667717957059132113",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 23796,
"createdBy": "project:chromium",
"createTime": "2026-10-17T06:03:44.707858660Z",
"updateTime": "2026-10-17T06:03:44.707858660Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "47dfd5dedef3fca08c136753acecaea51e5b515d",
"ref": "refs/heads/main"
}
}
}
[06:03:45.156] Task URL: https://cr-buildbucket.appspot.com/build/8667717957059132113
[06:03:45.156] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:2:0) after 7m32s
[06:03:46.125] Received PubSub notification, asking Buildbucket for the build status
[06:03:46.149] Build status: SCHEDULED
[06:05:33.724] Received PubSub notification, asking Buildbucket for the build status
[06:05:33.752] Build status: STARTED
[06:11:17.178] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:2:0)
[06:11:17.199] Build status: STARTED
[06:11:17.199] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:5:0) after 9m48s
[06:21:05.286] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:5:0)
[06:21:05.345] Build status: STARTED
[06:21:05.346] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:6:0) after 1m19s
[06:22:24.409] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:6:0)
[06:22:24.427] Build status: STARTED
[06:22:24.427] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:7:0) after 7m56s
[06:30:20.541] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:7:0)
[06:30:20.573] Build status: STARTED
[06:30:20.573] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:8:0) after 7m47s
[06:38:07.595] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:8:0)
[06:38:07.670] Build status: STARTED
[06:38:07.670] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:9:0) after 8m52s
[06:46:59.664] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:9:0)
[06:46:59.696] Build status: STARTED
[06:46:59.696] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:10:0) after 1m16s
[06:48:15.715] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:10:0)
[06:48:15.741] Build status: STARTED
[06:48:15.741] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:11:0) after 3m54s
[06:52:09.757] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:11:0)
[06:52:09.827] Build status: STARTED
[06:52:09.827] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:12:0) after 7m34s
[06:59:43.894] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:12:0)
[06:59:43.913] Build status: STARTED
[06:59:43.913] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:13:0) after 6m33s
[07:06:16.929] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:13:0)
[07:06:16.945] Build status: STARTED
[07:06:16.945] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:14:0) after 3m36s
[07:09:53.059] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:14:0)
[07:09:53.079] Build status: STARTED
[07:09:53.079] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:15:0) after 1m4s
[07:10:57.103] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:15:0)
[07:10:57.124] Build status: STARTED
[07:10:57.124] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:16:0) after 1m19s
[07:12:16.191] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:16:0)
[07:12:16.220] Build status: STARTED
[07:12:16.220] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:17:0) after 6m9s
[07:18:25.275] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:17:0)
[07:18:25.294] Build status: STARTED
[07:18:25.294] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:18:0) after 4m1s
[07:22:26.309] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:18:0)
[07:22:26.357] Build status: STARTED
[07:22:26.357] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:19:0) after 2m34s
[07:25:00.342] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:19:0)
[07:25:00.364] Build status: STARTED
[07:25:00.365] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:20:0) after 8m3s
[07:33:03.405] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:20:0)
[07:33:03.423] Build status: STARTED
[07:33:03.423] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:21:0) after 1m12s
[07:34:15.441] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:21:0)
[07:34:15.467] Build status: STARTED
[07:34:15.467] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:22:0) after 5m31s
[07:39:46.540] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:22:0)
[07:39:46.562] Build status: STARTED
[07:39:46.562] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:23:0) after 5m19s
[07:45:05.622] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:23:0)
[07:45:05.648] Build status: STARTED
[07:45:05.648] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:24:0) after 4m34s
[07:49:39.714] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:24:0)
[07:49:39.759] Build status: STARTED
[07:49:39.759] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:25:0) after 7m46s
[07:57:26.106] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:25:0)
[07:57:26.124] Build status: STARTED
[07:57:26.124] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:26:0) after 3m58s
[08:01:24.144] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:26:0)
[08:01:24.161] Build status: STARTED
[08:01:24.162] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:27:0) after 8m30s
[08:09:54.298] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:27:0)
[08:09:54.327] Build status: STARTED
[08:09:54.327] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:28:0) after 1m2s
[08:10:56.357] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:28:0)
[08:10:56.384] Build status: STARTED
[08:10:56.384] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:29:0) after 8m36s
[08:19:32.401] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:29:0)
[08:19:32.419] Build status: STARTED
[08:19:32.419] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:30:0) after 7m41s
[08:27:13.446] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:30:0)
[08:27:13.465] Build status: STARTED
[08:27:13.465] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:31:0) after 8m28s
[08:35:41.484] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:31:0)
[08:35:41.504] Build status: STARTED
[08:35:41.504] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:32:0) after 3m49s
[08:39:30.525] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:32:0)
[08:39:30.543] Build status: STARTED
[08:39:30.543] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:33:0) after 7m37s
[08:47:07.774] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:33:0)
[08:47:07.794] Build status: STARTED
[08:47:07.794] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:34:0) after 8m58s
[08:56:05.871] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:34:0)
[08:56:05.896] Build status: STARTED
[08:56:05.896] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:35:0) after 8m34s
[09:04:39.956] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:35:0)
[09:04:39.974] Build status: STARTED
[09:04:39.974] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:36:0) after 8m54s
[09:13:34.289] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:36:0)
[09:13:34.318] Build status: STARTED
[09:13:34.318] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:37:0) after 6m32s
[09:20:06.506] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:37:0)
[09:20:06.525] Build status: STARTED
[09:20:06.525] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:38:0) after 6m17s
[09:26:23.544] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:38:0)
[09:26:23.564] Build status: STARTED
[09:26:23.564] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:39:0) after 4m20s
[09:30:43.596] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:39:0)
[09:30:43.612] Build status: STARTED
[09:30:43.612] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:40:0) after 2m5s
[09:32:48.936] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:40:0)
[09:32:48.966] Build status: STARTED
[09:32:48.966] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:41:0) after 6m2s
[09:38:50.982] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:41:0)
[09:38:51.005] Build status: STARTED
[09:38:51.005] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:42:0) after 2m4s
[09:40:55.143] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:42:0)
[09:40:55.169] Build status: STARTED
[09:40:55.169] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:43:0) after 8m56s
[09:49:51.188] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:43:0)
[09:49:51.203] Build status: STARTED
[09:49:51.204] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:44:0) after 7m37s
[09:57:28.331] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:44:0)
[09:57:28.358] Build status: STARTED
[09:57:28.358] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:45:0) after 8m35s
[10:06:03.411] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:45:0)
[10:06:03.432] Build status: STARTED
[10:06:03.432] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:46:0) after 4m28s
[10:10:31.450] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:46:0)
[10:10:31.471] Build status: STARTED
[10:10:31.471] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:47:0) after 6m30s
[10:17:01.492] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:47:0)
[10:17:01.518] Build status: STARTED
[10:17:01.518] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:48:0) after 7m7s
[10:24:08.564] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:48:0)
[10:24:08.586] Build status: STARTED
[10:24:08.586] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:49:0) after 2m46s
[10:26:54.609] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:49:0)
[10:26:54.638] Build status: STARTED
[10:26:54.638] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:50:0) after 9m34s
[10:36:28.748] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:50:0)
[10:36:28.767] Build status: STARTED
[10:36:28.767] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:51:0) after 7m57s
[10:44:25.782] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:51:0)
[10:44:25.799] Build status: STARTED
[10:44:25.799] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:52:0) after 6m46s
[10:51:11.950] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:52:0)
[10:51:11.973] Build status: STARTED
[10:51:11.974] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:53:0) after 8m32s
[10:59:43.964] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:53:0)
[10:59:43.981] Build status: STARTED
[10:59:43.981] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8833148019038173488:54:0) after 6m14s
[11:03:27.795] Received PubSub notification, asking Buildbucket for the build status
[11:03:27.814] Build:
{
"id": "8667717957059132113",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 23796,
"createdBy": "project:chromium",
"createTime": "2026-10-17T06:03:44.707858660Z",
"startTime": "2026-10-17T06:05:33.530482919Z",
"endTime": "2026-10-17T11:03:27.431244264Z",
"updateTime": "2026-10-17T11:03:27.431244264Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "47dfd5dedef3fca08c136753acecaea51e5b515d",
"ref": "refs/heads/main"
}
}
}
[11:03:27.814] Invocation finished in 4h59m44.377989533s with status FAILED