[16:38:42.986] New invocation is queued and will start shortly
[16:38:45.108] Starting the invocation (attempt 1)
[16:38:45.146] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c6709d3ba143e2d39fa13e35ad71df4a0e009d6a"
[16:38:45.146] Popped gitiles commit info from properties and tags
[16:38:45.146] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:38:45.146] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:38:45.146] Buildbucket request:
{
"requestId": "8850412089356920240",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTChromeOS (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c6709d3ba143e2d39fa13e35ad71df4a0e009d6a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8850412089356920240"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTChromeOS (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:38:45.806] Scheduled build:
{
"id": "8684982026333070545",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTChromeOS (dbg)"
},
"number": 19512,
"createdBy": "project:chromium",
"createTime": "2026-04-09T16:38:45.244324563Z",
"updateTime": "2026-04-09T16:38:45.244324563Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c6709d3ba143e2d39fa13e35ad71df4a0e009d6a",
"ref": "refs/heads/main"
}
}
}
[16:38:45.806] Task URL: https://cr-buildbucket.appspot.com/build/8684982026333070545
[16:38:45.806] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:2:0) after 7m14s
[16:38:46.760] Received PubSub notification, asking Buildbucket for the build status
[16:38:46.789] Build status: SCHEDULED
[16:38:50.796] Received PubSub notification, asking Buildbucket for the build status
[16:38:50.816] Build status: STARTED
[16:45:59.832] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:2:0)
[16:45:59.849] Build status: STARTED
[16:45:59.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:5:0) after 1m38s
[16:47:37.872] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:5:0)
[16:47:37.890] Build status: STARTED
[16:47:37.890] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:6:0) after 3m24s
[16:51:01.954] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:6:0)
[16:51:01.981] Build status: STARTED
[16:51:01.981] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:7:0) after 1m31s
[16:52:33.022] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:7:0)
[16:52:33.061] Build status: STARTED
[16:52:33.061] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:8:0) after 4m20s
[16:56:53.086] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:8:0)
[16:56:53.108] Build status: STARTED
[16:56:53.108] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:9:0) after 9m5s
[17:05:58.140] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:9:0)
[17:05:58.164] Build status: STARTED
[17:05:58.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:10:0) after 3m24s
[17:09:22.184] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:10:0)
[17:09:22.242] Build status: STARTED
[17:09:22.242] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:11:0) after 4m17s
[17:13:39.296] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:11:0)
[17:13:39.320] Build status: STARTED
[17:13:39.320] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:12:0) after 9m50s
[17:23:29.379] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:12:0)
[17:23:29.400] Build status: STARTED
[17:23:29.401] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:13:0) after 5m52s
[17:29:21.461] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:13:0)
[17:29:21.489] Build status: STARTED
[17:29:21.489] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:14:0) after 3m31s
[17:32:52.506] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:14:0)
[17:32:52.526] Build status: STARTED
[17:32:52.526] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:15:0) after 5m30s
[17:38:22.683] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:15:0)
[17:38:22.714] Build status: STARTED
[17:38:22.714] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:16:0) after 1m7s
[17:39:29.826] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:16:0)
[17:39:29.849] Build status: STARTED
[17:39:29.849] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:17:0) after 5m9s
[17:44:38.830] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:17:0)
[17:44:38.847] Build status: STARTED
[17:44:38.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:18:0) after 7m9s
[17:51:47.915] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:18:0)
[17:51:47.933] Build status: STARTED
[17:51:47.933] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:19:0) after 8m16s
[18:00:03.953] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:19:0)
[18:00:03.967] Build status: STARTED
[18:00:03.967] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:20:0) after 2m15s
[18:02:19.099] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:20:0)
[18:02:19.153] Build status: STARTED
[18:02:19.153] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:21:0) after 2m3s
[18:04:22.328] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:21:0)
[18:04:22.348] Build status: STARTED
[18:04:22.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:22:0) after 7m40s
[18:12:02.402] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:22:0)
[18:12:02.506] Build status: STARTED
[18:12:02.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:23:0) after 2m45s
[18:14:47.529] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:23:0)
[18:14:47.576] Build status: STARTED
[18:14:47.576] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:24:0) after 5m15s
[18:20:02.704] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:24:0)
[18:20:02.722] Build status: STARTED
[18:20:02.722] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:25:0) after 6m16s
[18:26:18.744] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:25:0)
[18:26:18.763] Build status: STARTED
[18:26:18.763] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:26:0) after 8m55s
[18:35:13.833] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:26:0)
[18:35:13.851] Build status: STARTED
[18:35:13.852] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:27:0) after 5m17s
[18:41:57.362] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:27:0)
[18:41:57.383] Build status: STARTED
[18:41:57.383] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:28:0) after 8m36s
[18:50:33.451] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:28:0)
[18:50:33.472] Build status: STARTED
[18:50:33.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:29:0) after 6m4s
[18:56:37.497] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:29:0)
[18:56:37.570] Build status: STARTED
[18:56:37.570] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:30:0) after 5m22s
[19:01:59.606] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:30:0)
[19:01:59.631] Build status: STARTED
[19:01:59.631] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:31:0) after 2m10s
[19:04:09.860] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:31:0)
[19:04:09.889] Build status: STARTED
[19:04:09.889] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:32:0) after 7m47s
[19:11:57.129] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:32:0)
[19:11:57.148] Build status: STARTED
[19:11:57.148] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:33:0) after 7m39s
[19:19:36.156] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:33:0)
[19:19:36.186] Build status: STARTED
[19:19:36.186] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:34:0) after 2m35s
[19:22:11.210] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:34:0)
[19:22:11.270] Build status: STARTED
[19:22:11.270] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:35:0) after 9m16s
[19:31:27.355] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:35:0)
[19:31:27.375] Build status: STARTED
[19:31:27.375] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:36:0) after 9m56s
[19:41:23.411] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:36:0)
[19:41:23.438] Build status: STARTED
[19:41:23.438] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:37:0) after 4m21s
[19:45:44.459] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:37:0)
[19:45:44.477] Build status: STARTED
[19:45:44.477] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:38:0) after 1m29s
[19:47:13.650] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:38:0)
[19:47:13.666] Build status: STARTED
[19:47:13.666] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:39:0) after 6m51s
[19:54:04.762] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:39:0)
[19:54:04.782] Build status: STARTED
[19:54:04.782] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:40:0) after 2m9s
[19:56:13.850] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:40:0)
[19:56:13.867] Build status: STARTED
[19:56:13.867] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:41:0) after 4m12s
[20:00:25.887] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:41:0)
[20:00:25.902] Build status: STARTED
[20:00:25.902] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:42:0) after 2m16s
[20:02:41.932] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:42:0)
[20:02:41.956] Build status: STARTED
[20:02:41.956] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:43:0) after 5m57s
[20:08:39.031] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:43:0)
[20:08:39.053] Build status: STARTED
[20:08:39.053] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:44:0) after 2m53s
[20:11:32.131] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:44:0)
[20:11:32.150] Build status: STARTED
[20:11:32.150] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:45:0) after 1m1s
[20:12:33.169] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:45:0)
[20:12:33.245] Build status: STARTED
[20:12:33.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:46:0) after 4m38s
[20:17:11.317] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:46:0)
[20:17:11.343] Build status: STARTED
[20:17:11.343] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:47:0) after 6m29s
[20:23:40.652] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:47:0)
[20:23:40.672] Build status: STARTED
[20:23:40.672] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:48:0) after 5m27s
[20:29:07.673] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:48:0)
[20:29:07.695] Build status: STARTED
[20:29:07.695] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:49:0) after 8m14s
[20:37:21.777] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:49:0)
[20:37:21.791] Build status: STARTED
[20:37:21.791] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:50:0) after 7m58s
[20:45:19.860] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:50:0)
[20:45:19.929] Build status: STARTED
[20:45:19.929] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:51:0) after 5m20s
[20:50:40.020] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:51:0)
[20:50:40.050] Build status: STARTED
[20:50:40.050] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:52:0) after 3m46s
[20:54:26.182] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:52:0)
[20:54:26.202] Build status: STARTED
[20:54:26.202] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:53:0) after 5m27s
[20:59:53.301] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:53:0)
[20:59:53.355] Build status: STARTED
[20:59:53.356] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:54:0) after 3m8s
[21:03:01.474] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:54:0)
[21:03:01.491] Build status: STARTED
[21:03:01.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:55:0) after 8m2s
[21:11:03.507] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:55:0)
[21:11:03.589] Build status: STARTED
[21:11:03.589] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:56:0) after 6m7s
[21:17:10.585] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:56:0)
[21:17:10.601] Build status: STARTED
[21:17:10.601] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:57:0) after 6m46s
[21:23:56.663] Handling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:57:0)
[21:23:56.743] Build status: STARTED
[21:23:56.743] Scheduling timer "check-buildbucket-build-status" (chromium/ToTChromeOS (dbg):8850412089356920240:58:0) after 3m17s
[21:23:57.684] Received PubSub notification, asking Buildbucket for the build status
[21:23:57.701] Build:
{
"id": "8684982026333070545",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTChromeOS (dbg)"
},
"number": 19512,
"createdBy": "project:chromium",
"createTime": "2026-04-09T16:38:45.244324563Z",
"startTime": "2026-04-09T16:38:50.236055265Z",
"endTime": "2026-04-09T21:23:56.815430443Z",
"updateTime": "2026-04-09T21:23:56.815430443Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "c6709d3ba143e2d39fa13e35ad71df4a0e009d6a",
"ref": "refs/heads/main"
}
}
}
[21:23:57.701] Invocation finished in 4h45m14.725426865s with status SUCCEEDED