[02:42:58.888] New invocation is queued and will start shortly
[02:42:59.974] Starting the invocation (attempt 1)
[02:42:59.997] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/eac6b4698615d4e3d9c48876cb6e1ea1d011ef71"
[02:42:59.997] Popped gitiles commit info from properties and tags
[02:42:59.997] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:42:59.999] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:42:59.999] Buildbucket request:
{
"requestId": "8871211374560846544",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "eac6b4698615d4e3d9c48876cb6e1ea1d011ef71",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8871211374560846544"
},
{
"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"
}
}
[02:43:00.499] Scheduled build:
{
"id": "8705781312665545265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 39272,
"createdBy": "project:chromium",
"createTime": "2025-08-23T02:43:00.069170128Z",
"updateTime": "2025-08-23T02:43:00.069170128Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "eac6b4698615d4e3d9c48876cb6e1ea1d011ef71",
"ref": "refs/heads/main"
}
}
}
[02:43:00.499] Task URL: https://cr-buildbucket.appspot.com/build/8705781312665545265
[02:43:00.499] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:2:0) after 4m12s
[02:43:01.299] Received PubSub notification, asking Buildbucket for the build status
[02:43:01.316] Build status: SCHEDULED
[02:43:08.086] Received PubSub notification, asking Buildbucket for the build status
[02:43:08.104] Build status: STARTED
[02:47:12.564] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:2:0)
[02:47:12.581] Build status: STARTED
[02:47:12.581] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:5:0) after 6m25s
[02:53:38.289] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:5:0)
[02:53:38.315] Build status: STARTED
[02:53:38.316] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:6:0) after 3m39s
[02:57:17.596] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:6:0)
[02:57:17.613] Build status: STARTED
[02:57:17.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:7:0) after 2m10s
[02:59:27.923] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:7:0)
[02:59:27.948] Build status: STARTED
[02:59:27.948] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:8:0) after 7m11s
[03:06:39.009] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:8:0)
[03:06:39.026] Build status: STARTED
[03:06:39.026] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:9:0) after 9m24s
[03:16:03.067] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:9:0)
[03:16:03.115] Build status: STARTED
[03:16:03.115] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:10:0) after 8m11s
[03:24:14.520] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:10:0)
[03:24:14.538] Build status: STARTED
[03:24:14.538] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:11:0) after 8m27s
[03:32:41.592] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:11:0)
[03:32:41.613] Build status: STARTED
[03:32:41.613] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:12:0) after 5m19s
[03:38:00.791] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:12:0)
[03:38:00.811] Build status: STARTED
[03:38:00.812] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:13:0) after 1m13s
[03:39:13.837] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:13:0)
[03:39:13.893] Build status: STARTED
[03:39:13.893] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:14:0) after 5m1s
[03:44:15.153] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:14:0)
[03:44:15.169] Build status: STARTED
[03:44:15.169] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:15:0) after 9m18s
[03:53:33.523] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:15:0)
[03:53:33.542] Build status: STARTED
[03:53:33.542] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:16:0) after 6m21s
[03:59:54.643] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:16:0)
[03:59:54.661] Build status: STARTED
[03:59:54.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:17:0) after 1m57s
[04:01:51.859] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:17:0)
[04:01:51.883] Build status: STARTED
[04:01:51.883] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:18:0) after 1m1s
[04:02:53.003] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:18:0)
[04:02:53.033] Build status: STARTED
[04:02:53.033] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:19:0) after 4m48s
[04:07:41.051] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:19:0)
[04:07:41.073] Build status: STARTED
[04:07:41.073] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:20:0) after 7m53s
[04:15:34.106] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:20:0)
[04:15:34.124] Build status: STARTED
[04:15:34.124] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:21:0) after 9m59s
[04:25:33.234] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:21:0)
[04:25:33.249] Build status: STARTED
[04:25:33.249] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:22:0) after 1m33s
[04:27:06.491] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:22:0)
[04:27:06.513] Build status: STARTED
[04:27:06.513] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:23:0) after 6m11s
[04:33:17.548] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:23:0)
[04:33:17.604] Build status: STARTED
[04:33:17.604] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:24:0) after 1m18s
[04:34:35.747] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:24:0)
[04:34:35.768] Build status: STARTED
[04:34:35.768] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:25:0) after 8m13s
[04:42:48.853] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:25:0)
[04:42:48.914] Build status: STARTED
[04:42:48.914] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:26:0) after 1m32s
[04:44:21.021] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:26:0)
[04:44:21.038] Build status: STARTED
[04:44:21.038] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:27:0) after 5m33s
[04:49:54.198] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:27:0)
[04:49:54.211] Build status: STARTED
[04:49:54.211] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:28:0) after 3m18s
[04:53:12.258] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:28:0)
[04:53:12.275] Build status: STARTED
[04:53:12.275] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:29:0) after 1m48s
[04:55:00.290] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:29:0)
[04:55:00.309] Build status: STARTED
[04:55:00.309] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:30:0) after 9m9s
[05:04:09.605] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:30:0)
[05:04:09.625] Build status: STARTED
[05:04:09.625] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:31:0) after 3m35s
[05:07:45.088] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:31:0)
[05:07:45.104] Build status: STARTED
[05:07:45.104] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:32:0) after 9m6s
[05:16:51.161] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:32:0)
[05:16:51.209] Build status: STARTED
[05:16:51.209] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:33:0) after 4m48s
[05:21:39.226] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:33:0)
[05:21:39.243] Build status: STARTED
[05:21:39.244] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:34:0) after 1m37s
[05:23:16.263] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:34:0)
[05:23:16.295] Build status: STARTED
[05:23:16.295] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:35:0) after 8m32s
[05:31:48.316] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:35:0)
[05:31:48.348] Build status: STARTED
[05:31:48.348] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:36:0) after 9m35s
[05:41:23.683] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:36:0)
[05:41:23.699] Build status: STARTED
[05:41:23.699] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:37:0) after 2m32s
[05:43:56.208] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:37:0)
[05:43:56.240] Build status: STARTED
[05:43:56.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:38:0) after 2m7s
[05:46:03.295] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:38:0)
[05:46:03.340] Build status: STARTED
[05:46:03.340] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:39:0) after 5m6s
[05:51:09.501] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:39:0)
[05:51:09.519] Build status: STARTED
[05:51:09.519] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:40:0) after 6m47s
[05:57:56.578] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:40:0)
[05:57:56.640] Build status: STARTED
[05:57:56.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:41:0) after 4m56s
[06:02:52.641] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:41:0)
[06:02:52.755] Build status: STARTED
[06:02:52.755] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:42:0) after 4m13s
[06:07:05.873] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:42:0)
[06:07:05.889] Build status: STARTED
[06:07:05.889] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:43:0) after 7m6s
[06:14:12.095] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:43:0)
[06:14:12.140] Build status: STARTED
[06:14:12.140] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:44:0) after 5m56s
[06:20:08.168] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:44:0)
[06:20:08.204] Build status: STARTED
[06:20:08.204] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:45:0) after 4m53s
[06:25:01.548] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:45:0)
[06:25:01.572] Build status: STARTED
[06:25:01.572] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:46:0) after 6m3s
[06:31:04.698] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:46:0)
[06:31:04.714] Build status: STARTED
[06:31:04.715] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:47:0) after 2m7s
[06:33:11.729] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:47:0)
[06:33:11.745] Build status: STARTED
[06:33:11.745] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:48:0) after 2m46s
[06:35:57.789] Handling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:48:0)
[06:35:57.809] Build status: STARTED
[06:35:57.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTiOSDevice:8871211374560846544:49:0) after 8m6s
[06:36:29.921] Received PubSub notification, asking Buildbucket for the build status
[06:36:29.989] Build:
{
"id": "8705781312665545265",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTiOSDevice"
},
"number": 39272,
"createdBy": "project:chromium",
"createTime": "2025-08-23T02:43:00.069170128Z",
"startTime": "2025-08-23T02:43:06.244549990Z",
"endTime": "2025-08-23T06:36:29.507511190Z",
"updateTime": "2025-08-23T06:36:29.507511190Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "eac6b4698615d4e3d9c48876cb6e1ea1d011ef71",
"ref": "refs/heads/main"
}
}
}
[06:36:29.989] Invocation finished in 3h53m31.110212773s with status SUCCEEDED