[23:09:25.463] New invocation is queued and will start shortly
[23:09:26.553] Starting the invocation (attempt 1)
[23:09:26.584] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a6993c0b7aa9498b0a00fcfb85a20b4a64e12c4e"
[23:09:26.584] Popped gitiles commit info from properties and tags
[23:09:26.584] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:09:26.585] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:09:26.585] Buildbucket request:
{
"requestId": "8850387508133025920",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a6993c0b7aa9498b0a00fcfb85a20b4a64e12c4e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850387508133025920"
},
{
"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"
}
}
[23:09:27.318] Scheduled build:
{
"id": "8684957446231434497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 21004,
"createdBy": "project:chromium",
"createTime": "2026-04-09T23:09:26.655728414Z",
"updateTime": "2026-04-09T23:09:26.655728414Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a6993c0b7aa9498b0a00fcfb85a20b4a64e12c4e",
"ref": "refs/heads/main"
}
}
}
[23:09:27.318] Task URL: https://cr-buildbucket.appspot.com/build/8684957446231434497
[23:09:27.318] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:2:0) after 2m27s
[23:09:28.138] Received PubSub notification, asking Buildbucket for the build status
[23:09:28.169] Build status: SCHEDULED
[23:09:31.970] Received PubSub notification, asking Buildbucket for the build status
[23:09:31.994] Build status: STARTED
[23:11:54.337] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:2:0)
[23:11:54.358] Build status: STARTED
[23:11:54.358] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:5:0) after 8m36s
[23:20:30.607] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:5:0)
[23:20:30.632] Build status: STARTED
[23:20:30.632] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:6:0) after 8m19s
[23:28:49.627] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:6:0)
[23:28:49.645] Build status: STARTED
[23:28:49.645] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:7:0) after 9m11s
[23:38:00.663] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:7:0)
[23:38:00.734] Build status: STARTED
[23:38:00.734] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:8:0) after 3m36s
[23:41:36.770] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:8:0)
[23:41:36.818] Build status: STARTED
[23:41:36.818] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:9:0) after 3m18s
[23:44:54.920] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:9:0)
[23:44:54.943] Build status: STARTED
[23:44:54.943] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:10:0) after 8m23s
[23:53:18.010] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:10:0)
[23:53:18.038] Build status: STARTED
[23:53:18.038] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:11:0) after 4m57s
[23:58:15.179] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:11:0)
[23:58:15.268] Build status: STARTED
[23:58:15.268] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:12:0) after 4m48s
[00:03:03.349] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:12:0)
[00:03:03.368] Build status: STARTED
[00:03:03.368] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:13:0) after 1m27s
[00:04:30.454] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:13:0)
[00:04:30.474] Build status: STARTED
[00:04:30.474] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:14:0) after 8m42s
[00:13:12.500] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:14:0)
[00:13:12.517] Build status: STARTED
[00:13:12.517] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:15:0) after 8m50s
[00:22:02.696] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:15:0)
[00:22:02.765] Build status: STARTED
[00:22:02.765] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:16:0) after 2m46s
[00:24:48.785] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:16:0)
[00:24:48.802] Build status: STARTED
[00:24:48.802] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:17:0) after 6m56s
[00:31:44.922] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:17:0)
[00:31:44.939] Build status: STARTED
[00:31:44.940] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:18:0) after 2m0s
[00:33:45.029] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:18:0)
[00:33:45.050] Build status: STARTED
[00:33:45.050] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:19:0) after 7m44s
[00:41:29.064] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:19:0)
[00:41:29.094] Build status: STARTED
[00:41:29.094] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:20:0) after 2m11s
[00:43:40.216] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:20:0)
[00:43:40.235] Build status: STARTED
[00:43:40.235] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:21:0) after 1m27s
[00:45:07.250] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:21:0)
[00:45:07.268] Build status: STARTED
[00:45:07.268] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:22:0) after 1m20s
[00:46:27.421] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:22:0)
[00:46:27.646] Build status: STARTED
[00:46:27.646] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:23:0) after 3m1s
[00:49:28.716] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:23:0)
[00:49:28.733] Build status: STARTED
[00:49:28.734] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:24:0) after 2m11s
[00:51:39.786] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:24:0)
[00:51:39.804] Build status: STARTED
[00:51:39.804] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:25:0) after 3m10s
[00:54:49.828] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:25:0)
[00:54:49.846] Build status: STARTED
[00:54:49.846] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:26:0) after 5m54s
[01:00:43.999] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:26:0)
[01:00:44.016] Build status: STARTED
[01:00:44.016] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:27:0) after 7m12s
[01:07:56.116] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:27:0)
[01:07:56.142] Build status: STARTED
[01:07:56.142] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:28:0) after 5m26s
[01:13:22.159] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:28:0)
[01:13:22.183] Build status: STARTED
[01:13:22.183] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:29:0) after 6m40s
[01:20:02.258] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:29:0)
[01:20:02.296] Build status: STARTED
[01:20:02.296] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:30:0) after 5m24s
[01:25:26.415] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:30:0)
[01:25:26.462] Build status: STARTED
[01:25:26.462] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:31:0) after 2m35s
[01:28:01.478] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:31:0)
[01:28:01.505] Build status: STARTED
[01:28:01.505] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:32:0) after 5m4s
[01:33:05.525] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:32:0)
[01:33:05.541] Build status: STARTED
[01:33:05.541] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:33:0) after 1m16s
[01:34:21.612] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:33:0)
[01:34:21.627] Build status: STARTED
[01:34:21.627] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:34:0) after 5m36s
[01:39:57.648] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:34:0)
[01:39:57.663] Build status: STARTED
[01:39:57.663] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:35:0) after 2m14s
[01:42:11.689] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:35:0)
[01:42:11.785] Build status: STARTED
[01:42:11.785] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:36:0) after 6m13s
[01:48:24.896] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:36:0)
[01:48:24.913] Build status: STARTED
[01:48:24.913] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:37:0) after 1m12s
[01:49:36.951] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:37:0)
[01:49:36.970] Build status: STARTED
[01:49:36.970] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:38:0) after 9m51s
[01:59:28.043] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:38:0)
[01:59:28.060] Build status: STARTED
[01:59:28.060] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:39:0) after 9m55s
[02:09:23.077] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:39:0)
[02:09:23.094] Build status: STARTED
[02:09:23.094] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:40:0) after 7m55s
[02:17:18.150] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:40:0)
[02:17:18.170] Build status: STARTED
[02:17:18.170] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:41:0) after 2m48s
[02:20:06.170] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:41:0)
[02:20:06.187] Build status: STARTED
[02:20:06.187] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:42:0) after 6m27s
[02:26:33.234] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:42:0)
[02:26:33.254] Build status: STARTED
[02:26:33.254] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:43:0) after 2m48s
[02:29:21.278] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:43:0)
[02:29:21.323] Build status: STARTED
[02:29:21.323] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:44:0) after 6m18s
[02:35:39.343] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:44:0)
[02:35:39.357] Build status: STARTED
[02:35:39.357] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:45:0) after 8m0s
[02:43:39.459] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:45:0)
[02:43:39.485] Build status: STARTED
[02:43:39.485] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:46:0) after 3m8s
[02:46:47.592] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:46:0)
[02:46:47.617] Build status: STARTED
[02:46:47.617] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:47:0) after 8m49s
[02:55:36.784] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:47:0)
[02:55:36.806] Build status: STARTED
[02:55:36.806] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:48:0) after 7m8s
[03:02:44.850] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:48:0)
[03:02:44.871] Build status: STARTED
[03:02:44.871] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:49:0) after 5m42s
[03:08:26.898] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:49:0)
[03:08:26.919] Build status: STARTED
[03:08:26.919] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:50:0) after 6m0s
[03:14:26.940] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:50:0)
[03:14:26.956] Build status: STARTED
[03:14:26.956] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:51:0) after 2m23s
[03:16:49.985] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:51:0)
[03:16:50.015] Build status: STARTED
[03:16:50.015] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:52:0) after 5m28s
[03:22:18.039] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:52:0)
[03:22:18.071] Build status: STARTED
[03:22:18.072] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:53:0) after 4m16s
[03:26:34.091] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:53:0)
[03:26:34.189] Build status: STARTED
[03:26:34.189] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:54:0) after 3m54s
[03:30:28.345] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:54:0)
[03:30:28.395] Build status: STARTED
[03:30:28.395] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:55:0) after 8m18s
[03:38:46.377] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:55:0)
[03:38:46.393] Build status: STARTED
[03:38:46.393] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8850387508133025920:56:0) after 4m21s
[03:40:35.352] Received PubSub notification, asking Buildbucket for the build status
[03:40:35.368] Build:
{
"id": "8684957446231434497",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 21004,
"createdBy": "project:chromium",
"createTime": "2026-04-09T23:09:26.655728414Z",
"startTime": "2026-04-09T23:09:31.752496997Z",
"endTime": "2026-04-10T03:40:34.950942566Z",
"updateTime": "2026-04-10T03:40:34.950942566Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a6993c0b7aa9498b0a00fcfb85a20b4a64e12c4e",
"ref": "refs/heads/main"
}
}
}
[03:40:35.368] Invocation finished in 4h31m9.910153552s with status SUCCEEDED