[11:14:19.203] New invocation is queued and will start shortly
[11:14:20.452] Starting the invocation (attempt 1)
[11:14:20.476] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f8b0cf540be092a0b2fae0d97847fbc5474c0f7a"
[11:14:20.476] Popped gitiles commit info from properties and tags
[11:14:20.477] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:14:20.477] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:14:20.477] Buildbucket request:
{
"requestId": "8858495628617241088",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f8b0cf540be092a0b2fae0d97847fbc5474c0f7a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858495628617241088"
},
{
"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"
}
}
[11:14:20.939] Scheduled build:
{
"id": "8693065566583594433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 20282,
"createdBy": "project:chromium",
"createTime": "2026-01-10T11:14:20.521478355Z",
"updateTime": "2026-01-10T11:14:20.521478355Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f8b0cf540be092a0b2fae0d97847fbc5474c0f7a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[11:14:20.939] Task URL: https://cr-buildbucket.appspot.com/build/8693065566583594433
[11:14:20.939] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:2:0) after 8m26s
[11:14:21.887] Received PubSub notification, asking Buildbucket for the build status
[11:14:21.914] Build status: SCHEDULED
[11:15:40.114] Received PubSub notification, asking Buildbucket for the build status
[11:15:40.140] Build status: STARTED
[11:22:47.111] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:2:0)
[11:22:47.139] Build status: STARTED
[11:22:47.139] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:5:0) after 6m26s
[11:29:13.279] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:5:0)
[11:29:13.396] Build status: STARTED
[11:29:13.396] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:6:0) after 5m57s
[11:35:10.573] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:6:0)
[11:35:10.592] Build status: STARTED
[11:35:10.592] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:7:0) after 3m20s
[11:38:30.660] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:7:0)
[11:38:30.682] Build status: STARTED
[11:38:30.682] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:8:0) after 7m7s
[11:45:37.838] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:8:0)
[11:45:37.859] Build status: STARTED
[11:45:37.859] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:9:0) after 2m43s
[11:48:20.920] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:9:0)
[11:48:20.938] Build status: STARTED
[11:48:20.938] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:10:0) after 9m0s
[11:57:20.950] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:10:0)
[11:57:20.980] Build status: STARTED
[11:57:20.980] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:11:0) after 3m26s
[12:00:47.047] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:11:0)
[12:00:47.095] Build status: STARTED
[12:00:47.095] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:12:0) after 6m53s
[12:07:40.111] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:12:0)
[12:07:40.173] Build status: STARTED
[12:07:40.173] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:13:0) after 1m24s
[12:09:04.190] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:13:0)
[12:09:04.205] Build status: STARTED
[12:09:04.205] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:14:0) after 8m26s
[12:17:30.221] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:14:0)
[12:17:30.237] Build status: STARTED
[12:17:30.237] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:15:0) after 7m29s
[12:24:59.257] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:15:0)
[12:24:59.270] Build status: STARTED
[12:24:59.270] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:16:0) after 2m21s
[12:27:20.271] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:16:0)
[12:27:20.289] Build status: STARTED
[12:27:20.289] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:17:0) after 9m9s
[12:36:29.408] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:17:0)
[12:36:29.448] Build status: STARTED
[12:36:29.448] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:18:0) after 7m11s
[12:43:40.464] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:18:0)
[12:43:40.492] Build status: STARTED
[12:43:40.492] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:19:0) after 9m34s
[12:53:14.589] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:19:0)
[12:53:14.605] Build status: STARTED
[12:53:14.605] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:20:0) after 8m50s
[13:02:04.635] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:20:0)
[13:02:04.706] Build status: STARTED
[13:02:04.706] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:21:0) after 5m48s
[13:07:53.130] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:21:0)
[13:07:53.144] Build status: STARTED
[13:07:53.145] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:22:0) after 5m58s
[13:13:51.159] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:22:0)
[13:13:51.169] Build status: STARTED
[13:13:51.170] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:23:0) after 6m43s
[13:20:34.258] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:23:0)
[13:20:34.272] Build status: STARTED
[13:20:34.272] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:24:0) after 2m24s
[13:22:58.288] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:24:0)
[13:22:58.326] Build status: STARTED
[13:22:58.326] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:25:0) after 8m21s
[13:31:19.341] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:25:0)
[13:31:19.365] Build status: STARTED
[13:31:19.365] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:26:0) after 4m59s
[13:36:18.654] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:26:0)
[13:36:18.711] Build status: STARTED
[13:36:18.711] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:27:0) after 1m1s
[13:37:19.731] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:27:0)
[13:37:19.754] Build status: STARTED
[13:37:19.754] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:28:0) after 4m42s
[13:42:01.953] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:28:0)
[13:42:01.969] Build status: STARTED
[13:42:01.969] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:29:0) after 3m0s
[13:45:02.396] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:29:0)
[13:45:02.493] Build status: STARTED
[13:45:02.493] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:30:0) after 7m11s
[13:52:13.508] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:30:0)
[13:52:13.527] Build status: STARTED
[13:52:13.527] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:31:0) after 2m20s
[13:54:33.543] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:31:0)
[13:54:33.570] Build status: STARTED
[13:54:33.570] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:32:0) after 8m23s
[14:02:56.934] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:32:0)
[14:02:56.954] Build status: STARTED
[14:02:56.954] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:33:0) after 8m33s
[14:11:29.999] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:33:0)
[14:11:30.013] Build status: STARTED
[14:11:30.013] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:34:0) after 8m5s
[14:19:35.012] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:34:0)
[14:19:35.031] Build status: STARTED
[14:19:35.031] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:35:0) after 3m17s
[14:22:52.233] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:35:0)
[14:22:52.357] Build status: STARTED
[14:22:52.357] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:36:0) after 3m50s
[14:26:42.371] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:36:0)
[14:26:42.397] Build status: STARTED
[14:26:42.397] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:37:0) after 4m37s
[14:31:19.459] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:37:0)
[14:31:19.473] Build status: STARTED
[14:31:19.473] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:38:0) after 1m50s
[14:33:09.490] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:38:0)
[14:33:09.508] Build status: STARTED
[14:33:09.508] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:39:0) after 1m11s
[14:34:20.521] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:39:0)
[14:34:20.536] Build status: STARTED
[14:34:20.536] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:40:0) after 8m13s
[14:42:33.654] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:40:0)
[14:42:33.669] Build status: STARTED
[14:42:33.670] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:41:0) after 6m19s
[14:48:52.711] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:41:0)
[14:48:52.725] Build status: STARTED
[14:48:52.726] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:42:0) after 4m58s
[14:53:50.745] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:42:0)
[14:53:50.759] Build status: STARTED
[14:53:50.759] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:43:0) after 7m54s
[15:01:44.768] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:43:0)
[15:01:44.792] Build status: STARTED
[15:01:44.792] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:44:0) after 8m38s
[15:10:22.808] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:44:0)
[15:10:22.825] Build status: STARTED
[15:10:22.825] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:45:0) after 9m27s
[15:19:49.934] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:45:0)
[15:19:49.950] Build status: STARTED
[15:19:49.950] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:46:0) after 1m42s
[15:21:31.967] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:46:0)
[15:21:31.986] Build status: STARTED
[15:21:31.986] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:47:0) after 8m5s
[15:29:37.053] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:47:0)
[15:29:37.072] Build status: STARTED
[15:29:37.072] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:48:0) after 8m39s
[15:38:16.090] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:48:0)
[15:38:16.104] Build status: STARTED
[15:38:16.104] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:49:0) after 5m52s
[15:44:08.122] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:49:0)
[15:44:08.141] Build status: STARTED
[15:44:08.141] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:50:0) after 2m43s
[15:46:51.170] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:50:0)
[15:46:51.195] Build status: STARTED
[15:46:51.195] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:51:0) after 8m59s
[15:55:50.453] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:51:0)
[15:55:50.559] Build status: STARTED
[15:55:50.559] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:52:0) after 6m40s
[16:02:30.576] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:52:0)
[16:02:30.592] Build status: STARTED
[16:02:30.592] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:53:0) after 8m34s
[16:11:04.609] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:53:0)
[16:11:04.638] Build status: STARTED
[16:11:04.638] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:54:0) after 2m26s
[16:13:30.656] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:54:0)
[16:13:30.671] Build status: STARTED
[16:13:30.671] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:55:0) after 8m23s
[16:21:53.687] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:55:0)
[16:21:53.701] Build status: STARTED
[16:21:53.701] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:56:0) after 1m50s
[16:23:43.714] Handling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:56:0)
[16:23:43.729] Build status: STARTED
[16:23:43.729] Scheduling timer "check-buildbucket-build-status" (chromium/CrWinAsan:8858495628617241088:57:0) after 6m43s
[16:26:34.673] Received PubSub notification, asking Buildbucket for the build status
[16:26:34.685] Build:
{
"id": "8693065566583594433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "CrWinAsan"
},
"number": 20282,
"createdBy": "project:chromium",
"createTime": "2026-01-10T11:14:20.521478355Z",
"startTime": "2026-01-10T11:15:39.951441209Z",
"endTime": "2026-01-10T16:26:34.392331353Z",
"updateTime": "2026-01-10T16:26:34.392331353Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "f8b0cf540be092a0b2fae0d97847fbc5474c0f7a",
"ref": "refs/heads/main"
}
},
"canary": true
}
[16:26:34.686] Invocation finished in 5h12m15.487427302s with status SUCCEEDED