13:28:26 # run 2026-09-30T13:28:26.437912+02:00 since 2026-09-30T11:28:25Z
13:28:26 night-chain press -> 202 {"data": {"legs": ["db-dump", "tier2", "offsite", "update-leg"]}, "message": "started", "ok": true}
  LOG 2026/09/30 11:28:26 night_chain.go:108: [INFO] [night-chain] manual run started from 172.18.0.3:59634: [db-dump tier2 offsite update-leg]
  LOG 2026/09/30 11:28:26 night_chain.go:84: [INFO] [night-chain] manual run of tonight's chain: dump → second drive → off-site → update leg
  LOG 2026/09/30 11:28:26 night_chain.go:77: [INFO] [night-chain] db-dump: started
  LOG 2026/09/30 11:30:26 night_chain.go:82: [INFO] [night-chain] db-dump: done in 2m0s
  LOG 2026/09/30 11:30:26 night_chain.go:77: [INFO] [night-chain] tier2: started
  LOG 2026/09/30 11:30:34 night_chain.go:82: [INFO] [night-chain] tier2: done in 8s
  LOG 2026/09/30 11:30:34 night_chain.go:77: [INFO] [night-chain] offsite: started
  LOG 2026/09/30 11:34:19 night_chain.go:82: [INFO] [night-chain] offsite: done in 3m45s
  LOG 2026/09/30 11:34:19 night_chain.go:77: [INFO] [night-chain] update-leg: started
  LOG 2026/09/30 11:34:19 unattended.go:270: [INFO] [update-leg] started (manual-chain): window 02:30, no step starts at or after 16:49
  LOG 2026/09/30 11:34:19 update.go:536: [INFO] [stacks] update bookstack: accepted — guarded update started
  LOG 2026/09/30 11:34:19 unattended.go:336: [INFO] [update-leg] bookstack: step pressed bookstack=lscr.io/linuxserver/bookstack:26.05.5, bookstack-db=mariadb:12.3 → bookstack=lscr.io/linuxserver/bookstack:26.09.1, bookstack-db=mariadb:12.3
  LOG 2026/09/30 11:34:19 update.go:1365: [INFO] [stacks] update bookstack: phase checking
  LOG 2026/09/30 11:34:19 update.go:786: [INFO] [stacks] update bookstack: ladder — the last step (2 of 2) — the catalog's current definition
  LOG 2026/09/30 11:34:19 update.go:814: [INFO] [stacks] update bookstack: precondition met — Tier 2 (second drive) copy from 2026-09-30T11:28:28Z (6m0s old, limit 24h0m0s)
  LOG 2026/09/30 11:34:19 update.go:1365: [INFO] [stacks] update bookstack: phase safety-dump
  LOG 2026/09/30 11:34:19 update.go:846: [INFO] [stacks] update bookstack: safety dump done (1 file(s)) [/mnt/sys_drive/felhom-data/backups/primary/bookstack/db-dumps/pre-restore-20260930T113419Z-bookstack-mariadb.sql]
  LOG 2026/09/30 11:34:19 undo.go:401: [WARN] [stacks] update bookstack: volume(s) [bookstack_bookstack_config bookstack_bookstack_db_data] carry no compose label (recreated by a restore before v0.268.0 — R-658) — copied by name
13:34:21 leg started — window moved to 11:29 (now 13:34:21 = W+2h05m): POST /backups/window -> 303 /backups?flash=flash.backup.window_updated
  LOG 2026/09/30 11:34:20 undo.go:287: [INFO] [stacks] update bookstack: the undo copy will hold 2 named volume(s), 171.3 MiB
  LOG 2026/09/30 11:34:20 update.go:1365: [INFO] [stacks] update bookstack: phase pinning
  LOG 2026/09/30 11:34:20 pin.go:373: [INFO] [stacks] update bookstack: pin advanced to /opt/docker/felhom-controller/data/catalog-cache/templates/bookstack/docker-compose.yml (bookstack=lscr.io/linuxserver/bookstack:26.09.1, bookstack-db=mariadb:12.3)
  LOG 2026/09/30 11:34:20 update.go:1365: [INFO] [stacks] update bookstack: phase pulling
  LOG 2026/09/30 11:34:21 backup_handlers.go:65: [INFO] [web] backup window set to 11:29 (legs 11:29/12:29/13:14)
  LOG 2026/09/30 11:34:37 update.go:1365: [INFO] [stacks] update bookstack: phase copying
  LOG 2026/09/30 11:34:49 update.go:1365: [INFO] [stacks] update bookstack: phase copying
  LOG 2026/09/30 11:34:49 undo.go:437: [INFO] [stacks] update bookstack: copied bookstack_bookstack_config → bookstack_bookstack_config.pre-update-20260930T113449Z in 498ms
  LOG 2026/09/30 11:34:51 undo.go:437: [INFO] [stacks] update bookstack: copied bookstack_bookstack_db_data → bookstack_bookstack_db_data.pre-update-20260930T113449Z in 2.101s
  LOG 2026/09/30 11:34:51 update.go:1365: [INFO] [stacks] update bookstack: phase starting
  LOG 2026/09/30 11:34:58 update.go:1365: [INFO] [stacks] update bookstack: phase verifying
  LOG 2026/09/30 11:35:11 quiesce.go:255: [INFO] [quiesce] full-system backup due and inside its window, but the automatic update leg is running (it starts no step after 16:29) — deferring to the next poll (`09` decision 20)
  LOG 2026/09/30 11:35:13 update.go:997: [INFO] [stacks] update bookstack: healthy after 15s (the app's health check passed)
  LOG 2026/09/30 11:35:13 update.go:1033: [INFO] [stacks] update bookstack: DONE in 54s
  LOG 2026/09/30 11:35:14 unattended.go:343: [INFO] [update-leg] bookstack: step ended done after 55.1 s
  LOG 2026/09/30 11:35:14 update.go:536: [INFO] [stacks] update kimai: accepted — guarded update started
  LOG 2026/09/30 11:35:14 unattended.go:336: [INFO] [update-leg] kimai: step pressed kimai=kimai/kimai2:apache-2.57.0, kimai-db=mariadb:11.8 → kimai=kimai/kimai2:2.67.0, kimai-db=mariadb:11.8
  LOG 2026/09/30 11:35:14 update.go:1365: [INFO] [stacks] update kimai: phase checking
  LOG 2026/09/30 11:35:14 update.go:786: [INFO] [stacks] update kimai: ladder — the last step (2 of 2) — the catalog's current definition
  LOG 2026/09/30 11:35:14 update.go:814: [INFO] [stacks] update kimai: precondition met — Tier 2 (second drive) copy from 2026-09-30T11:28:27Z (7m0s old, limit 24h0m0s)
  LOG 2026/09/30 11:35:14 update.go:1365: [INFO] [stacks] update kimai: phase safety-dump
  LOG 2026/09/30 11:35:14 update.go:846: [INFO] [stacks] update kimai: safety dump done (1 file(s)) [/mnt/sys_drive/felhom-data/backups/primary/kimai/db-dumps/pre-restore-20260930T113514Z-kimai-mariadb.sql]
  LOG 2026/09/30 11:35:15 undo.go:287: [INFO] [stacks] update kimai: the undo copy will hold 2 named volume(s), 216.6 MiB
  LOG 2026/09/30 11:35:15 update.go:1365: [INFO] [stacks] update kimai: phase pinning
  LOG 2026/09/30 11:35:15 pin.go:373: [INFO] [stacks] update kimai: pin advanced to /opt/docker/felhom-controller/data/catalog-cache/templates/kimai/docker-compose.yml (kimai=kimai/kimai2:2.67.0, kimai-db=mariadb:11.8)
  LOG 2026/09/30 11:35:15 update.go:1365: [INFO] [stacks] update kimai: phase pulling
  LOG 2026/09/30 11:35:32 update.go:1365: [INFO] [stacks] update kimai: phase copying
  LOG 2026/09/30 11:35:46 update.go:1365: [INFO] [stacks] update kimai: phase copying
  LOG 2026/09/30 11:35:48 undo.go:437: [INFO] [stacks] update kimai: copied kimai_kimai_db_data → kimai_kimai_db_data.pre-update-20260930T113546Z in 2.464s
  LOG 2026/09/30 11:35:49 update.go:1365: [INFO] [stacks] update kimai: phase copying
  LOG 2026/09/30 11:35:51 undo.go:437: [INFO] [stacks] update kimai: copied kimai_kimai_var → kimai_kimai_var.pre-update-20260930T113546Z in 2.241s
  LOG 2026/09/30 11:35:51 update.go:1365: [INFO] [stacks] update kimai: phase starting
  LOG 2026/09/30 11:35:57 update.go:1365: [INFO] [stacks] update kimai: phase verifying
  LOG 2026/09/30 11:36:11 quiesce.go:255: [INFO] [quiesce] full-system backup due and inside its window, but the automatic update leg is running (it starts no step after 16:29) — deferring to the next poll (`09` decision 20)
  LOG 2026/09/30 11:36:28 update.go:997: [INFO] [stacks] update kimai: healthy after 30s (the app's health check passed)
  LOG 2026/09/30 11:36:28 update.go:1033: [INFO] [stacks] update kimai: DONE in 1m14s
  LOG 2026/09/30 11:36:29 unattended.go:343: [INFO] [update-leg] kimai: step ended done after 75.1 s
  LOG 2026/09/30 11:36:29 unattended.go:248: [INFO] [update-leg] update leg (manual-chain): done=2 undone=0 held=0 failed=0 skipped=0 in 2m10s [skipped: ]
  LOG 2026/09/30 11:36:29 night_chain.go:82: [INFO] [night-chain] update-leg: done in 2m10s
  LOG 2026/09/30 11:36:29 night_chain.go:93: [INFO] [night-chain] finished in 8m3s
  LOG 2026/09/30 11:37:11 quiesce.go:505: [INFO] [quiesce] backup due on 1 tier(s) — quiescing 10 stack(s): [adventurelog bentopdf bookstack calibre-web docmost kimai opengist paperless-ngx privatebin romm]
  LOG 2026/09/30 11:37:38 quiesce.go:554: [INFO] [quiesce] tier local: backup job backup-9201-1790768258239656621 started — polling
  LOG 2026/09/30 11:37:48 quiesce.go:623: [INFO] [quiesce] tier local: job backup-9201-1790768258239656621 snapshotted — resuming app early (8B.2)
  LOG 2026/09/30 11:37:48 quiesce.go:492: [INFO] [quiesce] unquiescing (snapshotted (early resume, last tier)): restarting 10 stack(s)
  LOG 2026/09/30 11:47:19 quiesce.go:628: [INFO] [quiesce] tier local: backup job backup-9201-1790768258239656621 done
13:48:25 lines kept: 24 → C/C-run-log.txt
