[19:58:20.388] New invocation is queued and will start shortly
[19:58:21.467] Starting the invocation (attempt 1)
[19:58:21.490] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1da759be18bf4aec1fac766b7b9b5457c7a3fb80"
[19:58:21.490] Popped gitiles commit info from properties and tags
[19:58:21.490] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:58:21.491] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:58:21.491] Buildbucket request:
{
"requestId": "8857284899591024576",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1da759be18bf4aec1fac766b7b9b5457c7a3fb80",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857284899591024576"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTiOSDevice"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:58:21.955] Scheduled build:
{
"id": "8691854837710092433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 40623,
"createdBy": "project:chromium",
"createTime": "2026-01-23T19:58:21.552858773Z",
"updateTime": "2026-01-23T19:58:21.552858773Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1da759be18bf4aec1fac766b7b9b5457c7a3fb80",
"ref": "refs/heads/main"
}
}
}
[19:58:21.955] Task URL: https://cr-buildbucket.appspot.com/build/8691854837710092433
[19:58:21.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:2:0) after 7m5s
[19:58:22.942] Received PubSub notification, asking Buildbucket for the build status
[19:58:22.958] Build status: SCHEDULED
[19:58:30.477] Received PubSub notification, asking Buildbucket for the build status
[19:58:30.492] Build status: STARTED
[20:05:26.960] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:2:0)
[20:05:26.992] Build status: STARTED
[20:05:26.992] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:5:0) after 9m59s
[20:15:26.014] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:5:0)
[20:15:26.030] Build status: STARTED
[20:15:26.030] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:6:0) after 1m2s
[20:16:28.098] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:6:0)
[20:16:28.109] Build status: STARTED
[20:16:28.109] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:7:0) after 2m31s
[20:18:59.228] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:7:0)
[20:18:59.245] Build status: STARTED
[20:18:59.245] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:8:0) after 2m3s
[20:21:02.397] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:8:0)
[20:21:02.416] Build status: STARTED
[20:21:02.416] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:9:0) after 1m47s
[20:22:49.451] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:9:0)
[20:22:49.466] Build status: STARTED
[20:22:49.466] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:10:0) after 1m18s
[20:24:07.525] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:10:0)
[20:24:07.595] Build status: STARTED
[20:24:07.595] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:11:0) after 3m24s
[20:27:31.616] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:11:0)
[20:27:31.632] Build status: STARTED
[20:27:31.632] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:12:0) after 7m26s
[20:34:57.785] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:12:0)
[20:34:57.835] Build status: STARTED
[20:34:57.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:13:0) after 4m11s
[20:39:08.893] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:13:0)
[20:39:08.969] Build status: STARTED
[20:39:08.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:14:0) after 4m23s
[20:43:32.024] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:14:0)
[20:43:32.039] Build status: STARTED
[20:43:32.039] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:15:0) after 4m59s
[20:48:31.106] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:15:0)
[20:48:31.119] Build status: STARTED
[20:48:31.119] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:16:0) after 3m13s
[20:51:44.226] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:16:0)
[20:51:44.243] Build status: STARTED
[20:51:44.243] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:17:0) after 1m58s
[20:53:42.286] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:17:0)
[20:53:42.300] Build status: STARTED
[20:53:42.300] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:18:0) after 1m24s
[20:55:06.285] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:18:0)
[20:55:06.307] Build status: STARTED
[20:55:06.307] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:19:0) after 3m31s
[20:58:37.452] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:19:0)
[20:58:37.478] Build status: STARTED
[20:58:37.478] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:20:0) after 5m3s
[21:03:40.548] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:20:0)
[21:03:40.575] Build status: STARTED
[21:03:40.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:21:0) after 7m34s
[21:11:14.604] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:21:0)
[21:11:14.649] Build status: STARTED
[21:11:14.650] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:22:0) after 2m34s
[21:13:48.673] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:22:0)
[21:13:48.710] Build status: STARTED
[21:13:48.710] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:23:0) after 3m40s
[21:17:28.808] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:23:0)
[21:17:28.835] Build status: STARTED
[21:17:28.835] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:24:0) after 7m36s
[21:25:04.969] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:24:0)
[21:25:04.987] Build status: STARTED
[21:25:04.987] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:25:0) after 5m8s
[21:30:13.066] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:25:0)
[21:30:13.134] Build status: STARTED
[21:30:13.134] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:26:0) after 5m54s
[21:36:07.163] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:26:0)
[21:36:07.182] Build status: STARTED
[21:36:07.182] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:27:0) after 1m25s
[21:37:32.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:27:0)
[21:37:32.367] Build status: STARTED
[21:37:32.367] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:28:0) after 9m47s
[21:47:19.408] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:28:0)
[21:47:19.425] Build status: STARTED
[21:47:19.425] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:29:0) after 6m16s
[21:53:35.500] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:29:0)
[21:53:35.519] Build status: STARTED
[21:53:35.519] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:30:0) after 1m25s
[21:55:00.493] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:30:0)
[21:55:00.514] Build status: STARTED
[21:55:00.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:31:0) after 1m19s
[21:56:19.491] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:31:0)
[21:56:19.506] Build status: STARTED
[21:56:19.506] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:32:0) after 5m2s
[22:01:21.613] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:32:0)
[22:01:21.648] Build status: STARTED
[22:01:21.648] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:33:0) after 6m43s
[22:08:04.761] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:33:0)
[22:08:04.837] Build status: STARTED
[22:08:04.837] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:34:0) after 9m18s
[22:17:22.858] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:34:0)
[22:17:22.871] Build status: STARTED
[22:17:22.872] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:35:0) after 6m22s
[22:23:45.000] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:35:0)
[22:23:45.019] Build status: STARTED
[22:23:45.019] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:36:0) after 5m14s
[22:28:59.040] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:36:0)
[22:28:59.142] Build status: STARTED
[22:28:59.142] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:37:0) after 3m36s
[22:32:35.265] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:37:0)
[22:32:35.280] Build status: STARTED
[22:32:35.280] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:38:0) after 9m10s
[22:41:45.299] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:38:0)
[22:41:45.321] Build status: STARTED
[22:41:45.321] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:39:0) after 1m7s
[22:42:52.340] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:39:0)
[22:42:52.474] Build status: STARTED
[22:42:52.474] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:40:0) after 9m50s
[22:52:42.493] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:40:0)
[22:52:42.517] Build status: STARTED
[22:52:42.517] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:41:0) after 5m48s
[22:58:30.549] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:41:0)
[22:58:30.575] Build status: STARTED
[22:58:30.575] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:42:0) after 6m37s
[23:05:07.594] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:42:0)
[23:05:07.640] Build status: STARTED
[23:05:07.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:43:0) after 1m55s
[23:07:02.760] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:43:0)
[23:07:02.777] Build status: STARTED
[23:07:02.777] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:44:0) after 7m18s
[23:14:20.762] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:44:0)
[23:14:20.817] Build status: STARTED
[23:14:20.817] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:45:0) after 1m46s
[23:16:06.984] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:45:0)
[23:16:07.028] Build status: STARTED
[23:16:07.028] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:46:0) after 4m29s
[23:20:36.078] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:46:0)
[23:20:36.094] Build status: STARTED
[23:20:36.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:47:0) after 1m0s
[23:21:36.170] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:47:0)
[23:21:36.193] Build status: STARTED
[23:21:36.193] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:48:0) after 9m50s
[23:31:26.240] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:48:0)
[23:31:26.254] Build status: STARTED
[23:31:26.254] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:49:0) after 6m15s
[23:37:41.271] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:49:0)
[23:37:41.283] Build status: STARTED
[23:37:41.283] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:50:0) after 9m6s
[23:46:47.441] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:50:0)
[23:46:47.455] Build status: STARTED
[23:46:47.455] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:51:0) after 5m14s
[23:52:01.534] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:51:0)
[23:52:01.550] Build status: STARTED
[23:52:01.550] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:52:0) after 3m49s
[23:55:50.522] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:52:0)
[23:55:50.546] Build status: STARTED
[23:55:50.546] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:53:0) after 6m33s
[00:02:23.563] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:53:0)
[00:02:23.579] Build status: STARTED
[00:02:23.579] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:54:0) after 9m8s
[00:11:31.627] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:54:0)
[00:11:31.679] Build status: STARTED
[00:11:31.679] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:55:0) after 2m15s
[00:13:46.698] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:55:0)
[00:13:46.720] Build status: STARTED
[00:13:46.720] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:56:0) after 4m38s
[00:18:24.789] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:56:0)
[00:18:24.827] Build status: STARTED
[00:18:24.827] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:57:0) after 4m51s
[00:23:15.970] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:57:0)
[00:23:15.986] Build status: STARTED
[00:23:15.986] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:58:0) after 3m0s
[00:26:16.045] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:58:0)
[00:26:16.094] Build status: STARTED
[00:26:16.094] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:59:0) after 1m43s
[00:27:59.084] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:59:0)
[00:27:59.099] Build status: STARTED
[00:27:59.099] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:60:0) after 9m15s
[00:37:14.127] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:60:0)
[00:37:14.140] Build status: STARTED
[00:37:14.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8857284899591024576:61:0) after 4m51s
[00:37:42.354] Received PubSub notification, asking Buildbucket for the build status
[00:37:42.368] Build:
{
"id": "8691854837710092433",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 40623,
"createdBy": "project:chromium",
"createTime": "2026-01-23T19:58:21.552858773Z",
"startTime": "2026-01-23T19:58:30.315312127Z",
"endTime": "2026-01-24T00:37:33.392741254Z",
"updateTime": "2026-01-24T00:37:33.392741254Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1da759be18bf4aec1fac766b7b9b5457c7a3fb80",
"ref": "refs/heads/main"
}
}
}
[00:37:42.368] Invocation finished in 4h39m21.993118648s with status SUCCEEDED