Feb 11 23:04:01 volumiopi4 volumio[1253]: info: 0 upgraded, 34 newly installed, 0 to remove and 26 not upgraded. Feb 11 23:04:01 volumiopi4 volumio[1253]: info: Need to get 0 B/39.3 MB of archives. Feb 11 23:04:01 volumiopi4 volumio[1253]: info: After this operation, 102 MB of additional disk space will be used. Feb 11 23:04:01 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:01.402+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:01 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:01.412+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:01 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:01.415+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:01 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-ipafont-gothic. Feb 11 23:04:01 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:01.491+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:01 volumiopi4 volumio[1253]: [620B blob data] Feb 11 23:04:01 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:01.617+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:01 volumiopi4 volumio[1253]: info: Preparing to unpack .../00-fonts-ipafont-gothic_00303-23_all.deb ... Feb 11 23:04:01 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:01.630+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:01 volumiopi4 volumio[1253]: info: Unpacking fonts-ipafont-gothic (00303-23) ... Feb 11 23:04:01 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:01.648+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:02 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-ipafont-mincho. Feb 11 23:04:02 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:02.754+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:02 volumiopi4 volumio[1253]: info: Preparing to unpack .../01-fonts-ipafont-mincho_00303-23_all.deb ... Feb 11 23:04:02 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:02.769+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:02 volumiopi4 volumio[1253]: info: Unpacking fonts-ipafont-mincho (00303-23) ... Feb 11 23:04:02 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:02.792+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:04 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-ipafont. Feb 11 23:04:04 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:04.194+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:04 volumiopi4 volumio[1253]: info: Preparing to unpack .../02-fonts-ipafont_00303-23_all.deb ... Feb 11 23:04:04 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:04.213+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:04 volumiopi4 volumio[1253]: info: Unpacking fonts-ipafont (00303-23) ... Feb 11 23:04:04 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:04.227+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:04 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-unfonts-core. Feb 11 23:04:04 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:04.350+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:04 volumiopi4 volumio[1253]: info: Preparing to unpack .../03-fonts-unfonts-core_1%3a1.0.2-080608-18_all.deb ... Feb 11 23:04:04 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:04.361+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:04 volumiopi4 volumio[1253]: info: Unpacking fonts-unfonts-core (1:1.0.2-080608-18) ... Feb 11 23:04:04 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:04.397+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:07 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-arphic-gbsn00lp. Feb 11 23:04:07 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:07.175+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:07 volumiopi4 volumio[1253]: info: Preparing to unpack .../04-fonts-arphic-gbsn00lp_2.11-16_all.deb ... Feb 11 23:04:07 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:07.192+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:07 volumiopi4 volumio[1253]: info: Unpacking fonts-arphic-gbsn00lp (2.11-16) ... Feb 11 23:04:07 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:07.204+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:07 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-arphic-ukai. Feb 11 23:04:07 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:07.747+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:07 volumiopi4 volumio[1253]: info: Preparing to unpack .../05-fonts-arphic-ukai_0.2.20080216.2-5_all.deb ... Feb 11 23:04:07 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:07.760+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:07 volumiopi4 volumio[1253]: info: Unpacking fonts-arphic-ukai (0.2.20080216.2-5) ... Feb 11 23:04:07 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:07.785+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:09 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-garuda-ttf. Feb 11 23:04:09 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:09.666+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:09 volumiopi4 volumio[1253]: info: Preparing to unpack .../06-fonts-tlwg-garuda-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:09 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:09.680+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:09 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-garuda-ttf (1:0.7.3-1) ... Feb 11 23:04:09 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:09.691+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:09 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-kinnari-ttf. Feb 11 23:04:09 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:09.858+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:09 volumiopi4 volumio[1253]: info: Preparing to unpack .../07-fonts-tlwg-kinnari-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:09 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:09.870+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:09 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-kinnari-ttf (1:0.7.3-1) ... Feb 11 23:04:09 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:09.887+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-laksaman-ttf. Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.089+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Preparing to unpack .../08-fonts-tlwg-laksaman-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.106+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-laksaman-ttf (1:0.7.3-1) ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.122+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-loma-ttf. Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.289+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Preparing to unpack .../09-fonts-tlwg-loma-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.304+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-loma-ttf (1:0.7.3-1) ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.316+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-mono-ttf. Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.478+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Preparing to unpack .../10-fonts-tlwg-mono-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.491+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-mono-ttf (1:0.7.3-1) ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.504+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-norasi-ttf. Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.671+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Preparing to unpack .../11-fonts-tlwg-norasi-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.685+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-norasi-ttf (1:0.7.3-1) ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.700+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-purisa-ttf. Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.911+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Preparing to unpack .../12-fonts-tlwg-purisa-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.921+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:10 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-purisa-ttf (1:0.7.3-1) ... Feb 11 23:04:10 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:10.938+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-sawasdee-ttf. Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.129+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Preparing to unpack .../13-fonts-tlwg-sawasdee-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.142+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-sawasdee-ttf (1:0.7.3-1) ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.155+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-typewriter-ttf. Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.323+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Preparing to unpack .../14-fonts-tlwg-typewriter-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.337+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-typewriter-ttf (1:0.7.3-1) ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.350+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-typist-ttf. Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.517+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Preparing to unpack .../15-fonts-tlwg-typist-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.544+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-typist-ttf (1:0.7.3-1) ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.557+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-typo-ttf. Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.724+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Preparing to unpack .../16-fonts-tlwg-typo-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.739+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-typo-ttf (1:0.7.3-1) ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.754+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-umpush-ttf. Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.946+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Preparing to unpack .../17-fonts-tlwg-umpush-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.959+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:11 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-umpush-ttf (1:0.7.3-1) ... Feb 11 23:04:11 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:11.968+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-waree-ttf. Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.168+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Preparing to unpack .../18-fonts-tlwg-waree-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.182+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-waree-ttf (1:0.7.3-1) ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.196+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-thai-tlwg-ttf. Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.358+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Preparing to unpack .../19-fonts-thai-tlwg-ttf_1%3a0.7.3-1_all.deb ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.372+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Unpacking fonts-thai-tlwg-ttf (1:0.7.3-1) ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.392+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-garuda. Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.502+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Preparing to unpack .../20-fonts-tlwg-garuda_1%3a0.7.3-1_all.deb ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.517+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-garuda (1:0.7.3-1) ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.556+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-kinnari. Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.704+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Preparing to unpack .../21-fonts-tlwg-kinnari_1%3a0.7.3-1_all.deb ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.718+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-kinnari (1:0.7.3-1) ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.755+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-laksaman. Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.933+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Preparing to unpack .../22-fonts-tlwg-laksaman_1%3a0.7.3-1_all.deb ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.953+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:12 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-laksaman (1:0.7.3-1) ... Feb 11 23:04:12 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:12.992+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-loma. Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.164+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Preparing to unpack .../23-fonts-tlwg-loma_1%3a0.7.3-1_all.deb ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.175+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-loma (1:0.7.3-1) ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.204+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-mono. Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.373+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Preparing to unpack .../24-fonts-tlwg-mono_1%3a0.7.3-1_all.deb ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.385+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-mono (1:0.7.3-1) ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.410+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-norasi. Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.553+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Preparing to unpack .../25-fonts-tlwg-norasi_1%3a0.7.3-1_all.deb ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.566+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-norasi (1:0.7.3-1) ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.600+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-purisa. Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.721+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Preparing to unpack .../26-fonts-tlwg-purisa_1%3a0.7.3-1_all.deb ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.751+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-purisa (1:0.7.3-1) ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.782+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-sawasdee. Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.926+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Preparing to unpack .../27-fonts-tlwg-sawasdee_1%3a0.7.3-1_all.deb ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.941+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:13 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-sawasdee (1:0.7.3-1) ... Feb 11 23:04:13 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:13.956+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-typewriter. Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.070+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Preparing to unpack .../28-fonts-tlwg-typewriter_1%3a0.7.3-1_all.deb ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.088+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-typewriter (1:0.7.3-1) ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.102+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-typist. Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.244+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Preparing to unpack .../29-fonts-tlwg-typist_1%3a0.7.3-1_all.deb ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.264+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-typist (1:0.7.3-1) ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.285+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-typo. Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.439+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Preparing to unpack .../30-fonts-tlwg-typo_1%3a0.7.3-1_all.deb ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.453+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-typo (1:0.7.3-1) ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.480+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-umpush. Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.661+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Preparing to unpack .../31-fonts-tlwg-umpush_1%3a0.7.3-1_all.deb ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.676+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-umpush (1:0.7.3-1) ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.708+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-tlwg-waree. Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.874+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Preparing to unpack .../32-fonts-tlwg-waree_1%3a0.7.3-1_all.deb ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.895+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:14 volumiopi4 volumio[1253]: info: Unpacking fonts-tlwg-waree (1:0.7.3-1) ... Feb 11 23:04:14 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:14.916+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:15 volumiopi4 volumio[1253]: info: Selecting previously unselected package fonts-vlgothic. Feb 11 23:04:15 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:15.069+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:15 volumiopi4 volumio[1253]: info: Preparing to unpack .../33-fonts-vlgothic_20220612-1_all.deb ... Feb 11 23:04:15 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:15.087+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:15 volumiopi4 volumio[1253]: info: Unpacking fonts-vlgothic (20220612-1) ... Feb 11 23:04:15 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:15.103+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:15 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-garuda-ttf (1:0.7.3-1) ... Feb 11 23:04:15 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:15.927+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:15 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-laksaman-ttf (1:0.7.3-1) ... Feb 11 23:04:15 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:15.966+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:15 volumiopi4 volumio[1253]: info: Setting up fonts-unfonts-core (1:1.0.2-080608-18) ... Feb 11 23:04:15 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:15.996+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-loma-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.045+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-typo-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.094+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-kinnari-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.130+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-arphic-gbsn00lp (2.11-16) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.162+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-umpush-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.194+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-kinnari (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.232+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-vlgothic (20220612-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.284+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: update-alternatives: using /usr/share/fonts/truetype/vlgothic/VL-Gothic-Regular.ttf to provide /usr/share/fonts/truetype/fonts-japanese-gothic.ttf (fonts-japanese-gothic.ttf) in auto mode Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.307+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-laksaman (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.342+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-sawasdee-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.389+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-typist-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.435+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-typewriter-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.468+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-ipafont-mincho (00303-23) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.504+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: update-alternatives: using /usr/share/fonts/opentype/ipafont-mincho/ipam.ttf to provide /usr/share/fonts/truetype/fonts-japanese-mincho.ttf (fonts-japanese-mincho.ttf) in auto mode Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.532+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-ipafont-gothic (00303-23) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.551+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: update-alternatives: using /usr/share/fonts/opentype/ipafont-gothic/ipag.ttf to provide /usr/share/fonts/truetype/fonts-japanese-gothic.ttf (fonts-japanese-gothic.ttf) in auto mode Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.586+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-garuda (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.611+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-norasi-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.660+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-waree-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.689+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-loma (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.728+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-purisa-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.769+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-mono-ttf (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.793+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-arphic-ukai (0.2.20080216.2-5) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.826+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-typewriter (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.968+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:16 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-typo (1:0.7.3-1) ... Feb 11 23:04:16 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:16.992+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-waree (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.045+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-thai-tlwg-ttf (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.086+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-sawasdee (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.115+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-typist (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.149+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-umpush (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.189+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-ipafont (00303-23) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.253+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-mono (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.286+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-purisa (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.332+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Setting up fonts-tlwg-norasi (1:0.7.3-1) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.368+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:17 volumiopi4 volumio[1253]: info: Processing triggers for fontconfig (2.14.1-4) ... Feb 11 23:04:17 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:17.413+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 11 23:04:18 volumiopi4 kernel: apt-get invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Feb 11 23:04:18 volumiopi4 kernel: CPU: 2 UID: 0 PID: 5781 Comm: apt-get Tainted: G C 6.12.47-v7l+ #1904 Feb 11 23:04:18 volumiopi4 kernel: Tainted: [C]=CRAP Feb 11 23:04:18 volumiopi4 kernel: Hardware name: BCM2711 Feb 11 23:04:18 volumiopi4 kernel: Call trace: Feb 11 23:04:18 volumiopi4 kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 11 23:04:18 volumiopi4 kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 11 23:04:18 volumiopi4 kernel: dump_stack_lvl from dump_header+0x50/0x180 Feb 11 23:04:18 volumiopi4 kernel: dump_header from oom_kill_process+0x230/0x2a0 Feb 11 23:04:18 volumiopi4 kernel: oom_kill_process from out_of_memory+0x104/0x34c Feb 11 23:04:18 volumiopi4 kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Feb 11 23:04:18 volumiopi4 kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Feb 11 23:04:18 volumiopi4 kernel: new_slab from ___slab_alloc+0x570/0x958 Feb 11 23:04:18 volumiopi4 kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Feb 11 23:04:18 volumiopi4 kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Feb 11 23:04:18 volumiopi4 kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Feb 11 23:04:18 volumiopi4 kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Feb 11 23:04:18 volumiopi4 kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Feb 11 23:04:18 volumiopi4 kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Feb 11 23:04:18 volumiopi4 kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Feb 11 23:04:18 volumiopi4 kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Feb 11 23:04:18 volumiopi4 kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Feb 11 23:04:18 volumiopi4 kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Feb 11 23:04:18 volumiopi4 kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Feb 11 23:04:18 volumiopi4 kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Feb 11 23:04:18 volumiopi4 kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Feb 11 23:04:18 volumiopi4 kernel: vfs_write from ksys_write+0x68/0xec Feb 11 23:04:18 volumiopi4 kernel: ksys_write from ret_fast_syscall+0x0/0x5c Feb 11 23:04:18 volumiopi4 kernel: Exception stack(0xf0af9fa8 to 0xf0af9ff0) Feb 11 23:04:18 volumiopi4 kernel: 9fa0: 02515d9d bebbb04c 0000000e aec72000 02515d9d 00000001 Feb 11 23:04:18 volumiopi4 kernel: 9fc0: 02515d9d bebbb04c 00000000 00000004 b6e2d4d4 b6a02584 bebbb130 bebbb110 Feb 11 23:04:18 volumiopi4 kernel: 9fe0: b6f1a710 bebbafe0 b6e27978 b6b047ac Feb 11 23:04:18 volumiopi4 kernel: Mem-Info: Feb 11 23:04:18 volumiopi4 kernel: active_anon:353 inactive_anon:63840 isolated_anon:0 active_file:53275 inactive_file:185943 isolated_file:0 unevictable:4092 dirty:9164 writeback:0 slab_reclaimable:18129 slab_unreclaimable:6271 mapped:51935 shmem:2034 pagetables:1611 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:627908 free_pcp:8 free_cma:125960 Feb 11 23:04:18 volumiopi4 kernel: Node 0 active_anon:1412kB inactive_anon:255360kB active_file:213100kB inactive_file:743772kB unevictable:16368kB isolated(anon):0kB isolated(file):0kB mapped:207740kB dirty:36656kB writeback:0kB shmem:8136kB writeback_tmp:0kB kernel_stack:2560kB pagetables:6444kB sec_pagetables:0kB all_unreclaimable? no Feb 11 23:04:18 volumiopi4 kernel: DMA free:532040kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:15088kB inactive_file:388kB unevictable:0kB writepending:756kB present:786432kB managed:662964kB mlocked:0kB bounce:0kB free_pcp:32kB local_pcp:0kB free_cma:503840kB Feb 11 23:04:18 volumiopi4 kernel: lowmem_reserve[]: 0 0 3136 3136 Feb 11 23:04:18 volumiopi4 kernel: DMA: 382*4kB (UE) 398*8kB (UEC) 216*16kB (UEC) 155*32kB (UEC) 90*64kB (UMEC) 53*128kB (UEC) 14*256kB (UC) 11*512kB (UMC) 4*1024kB (EC) 1*2048kB (C) 120*4096kB (C) = 532552kB Feb 11 23:04:18 volumiopi4 kernel: 242819 total pagecache pages Feb 11 23:04:18 volumiopi4 kernel: 0 pages in swap cache Feb 11 23:04:18 volumiopi4 kernel: Free swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: Total swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: 999424 pages RAM Feb 11 23:04:18 volumiopi4 kernel: 802816 pages HighMem/MovableOnly Feb 11 23:04:18 volumiopi4 kernel: 30867 pages reserved Feb 11 23:04:18 volumiopi4 kernel: 131072 pages cma reserved Feb 11 23:04:18 volumiopi4 kernel: Tasks state (memory values in pages): Feb 11 23:04:18 volumiopi4 kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Feb 11 23:04:18 volumiopi4 kernel: [ 463] 0 463 7671 2115 192 1443 480 65536 0 -250 systemd-journal Feb 11 23:04:18 volumiopi4 kernel: [ 485] 0 485 5991 1186 256 930 0 53248 0 -1000 systemd-udevd Feb 11 23:04:18 volumiopi4 kernel: [ 699] 0 699 1959 1130 765 365 0 40960 0 0 haveged Feb 11 23:04:18 volumiopi4 kernel: [ 702] 108 702 1731 773 64 709 0 40960 0 0 rpcbind Feb 11 23:04:18 volumiopi4 kernel: [ 756] 0 756 2740 1302 64 1238 0 45056 0 0 bluetoothd Feb 11 23:04:18 volumiopi4 kernel: [ 757] 101 757 1916 958 128 830 0 40960 0 -900 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 769] 0 769 7706 1652 160 1492 0 61440 0 0 systemd-logind Feb 11 23:04:18 volumiopi4 kernel: [ 770] 65534 770 1250 595 32 563 0 32768 0 0 thd Feb 11 23:04:18 volumiopi4 kernel: [ 774] 1000 774 11915 2596 316 2280 0 81920 0 0 volumio-remote- Feb 11 23:04:18 volumiopi4 kernel: [ 778] 0 778 32098 10047 1336 8711 0 258048 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 898] 100 898 639 431 64 367 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 899] 0 899 643 499 64 435 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 902] 100 902 560 278 32 246 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 903] 100 903 560 279 32 247 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 936] 0 936 3275 1336 96 1240 0 49152 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 940] 0 940 10966 1669 96 1573 0 69632 0 0 bluealsa Feb 11 23:04:18 volumiopi4 kernel: [ 1037] 0 1037 1409 646 32 614 0 36864 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1040] 0 1040 930 582 32 550 0 28672 0 0 ip Feb 11 23:04:18 volumiopi4 kernel: [ 1041] 0 1041 1393 461 32 429 0 32768 0 0 sed Feb 11 23:04:18 volumiopi4 kernel: [ 1042] 0 1042 1409 416 33 383 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1044] 106 1044 4360 4025 2454 1571 0 65536 0 0 ntpd Feb 11 23:04:18 volumiopi4 kernel: [ 1065] 0 1065 520 348 0 348 0 24576 0 0 ifplugd Feb 11 23:04:18 volumiopi4 kernel: [ 1072] 0 1072 3391 1970 192 1778 0 53248 0 -1000 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1119] 0 1119 16457 3211 416 2726 69 94208 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1123] 0 1123 16383 1024 406 618 0 86016 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1216] 0 1216 3393 1459 196 1263 0 53248 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 1237] 100 1237 643 296 33 263 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 1253] 1000 1253 75212 36920 24433 12487 0 1105920 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1273] 0 1273 1115 460 0 460 0 36864 0 0 agetty Feb 11 23:04:18 volumiopi4 kernel: [ 1317] 0 1317 18061 3947 480 3422 45 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1323] 0 1323 18121 3613 482 3088 43 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1354] 0 1354 19290 4326 512 3620 194 118784 0 0 smbd Feb 11 23:04:18 volumiopi4 kernel: [ 1355] 103 1355 1572 831 64 767 0 36864 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1356] 103 1356 1545 419 64 355 0 32768 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1380] 0 1380 18897 2658 501 2157 0 110592 0 0 smbd-notifyd Feb 11 23:04:18 volumiopi4 kernel: [ 1381] 0 1381 18897 1261 501 760 0 110592 0 0 cleanupd Feb 11 23:04:18 volumiopi4 kernel: [ 1386] 0 1386 18061 2676 485 2191 0 106496 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1403] 0 1403 3669 2242 224 2018 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1410] 1000 1410 4062 2257 256 2001 0 61440 0 100 systemd Feb 11 23:04:18 volumiopi4 kernel: [ 1411] 1000 1411 8898 1095 598 497 0 65536 0 100 (sd-pam) Feb 11 23:04:18 volumiopi4 kernel: [ 1426] 1000 1426 1859 822 64 758 0 40960 0 200 mpris-proxy Feb 11 23:04:18 volumiopi4 kernel: [ 1430] 1000 1430 1792 940 64 876 0 36864 0 200 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1432] 1000 1432 1400 631 32 599 0 36864 0 0 network_monitor Feb 11 23:04:18 volumiopi4 kernel: [ 1465] 1000 1465 3734 1569 311 1258 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1472] 1000 1472 580 421 32 389 0 28672 0 0 sftp-server Feb 11 23:04:18 volumiopi4 kernel: [ 1485] 1000 1485 31905 8495 931 7564 0 233472 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1495] 1000 1495 33078 11486 2226 9260 0 307200 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1496] 1000 1496 32925 11523 2225 9298 0 303104 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1497] 1000 1497 32989 11517 2202 9315 0 311296 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1562] 0 1562 161161 5997 1554 4443 0 155648 0 0 volumio5-onboar Feb 11 23:04:18 volumiopi4 kernel: [ 1597] 102 1597 119125 23055 5916 17139 0 643072 0 0 mpd Feb 11 23:04:18 volumiopi4 kernel: [ 1664] 104 1664 37337 2884 384 2500 0 143360 0 0 shairport-sync Feb 11 23:04:18 volumiopi4 kernel: [ 1736] 1000 1736 31956 3009 288 2721 0 118784 0 0 upmpdcli Feb 11 23:04:18 volumiopi4 kernel: [ 2043] 0 2043 3669 2240 224 2016 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2068] 1000 2068 3734 1569 311 1258 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2069] 1000 2069 983 846 224 622 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 3529] 1000 3529 515 311 0 311 0 28672 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 3531] 1000 3531 2017 921 64 857 0 45056 0 0 sudo Feb 11 23:04:18 volumiopi4 kernel: [ 3532] 0 3532 515 322 0 322 0 32768 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 5781] 0 5781 24793 23406 12105 11301 0 229376 0 0 apt-get Feb 11 23:04:18 volumiopi4 kernel: [ 5906] 1000 5906 1115 349 0 349 0 32768 0 0 sleep Feb 11 23:04:18 volumiopi4 kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=dbus-daemon,pid=1430,uid=1000 Feb 11 23:04:18 volumiopi4 kernel: Out of memory: Killed process 1430 (dbus-daemon) total-vm:7168kB, anon-rss:256kB, file-rss:3504kB, shmem-rss:0kB, UID:1000 pgtables:36kB oom_score_adj:200 Feb 11 23:04:18 volumiopi4 kernel: apt-get invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Feb 11 23:04:18 volumiopi4 kernel: CPU: 2 UID: 0 PID: 5781 Comm: apt-get Tainted: G C 6.12.47-v7l+ #1904 Feb 11 23:04:18 volumiopi4 kernel: Tainted: [C]=CRAP Feb 11 23:04:18 volumiopi4 kernel: Hardware name: BCM2711 Feb 11 23:04:18 volumiopi4 kernel: Call trace: Feb 11 23:04:18 volumiopi4 kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 11 23:04:18 volumiopi4 kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 11 23:04:18 volumiopi4 kernel: dump_stack_lvl from dump_header+0x50/0x180 Feb 11 23:04:18 volumiopi4 kernel: dump_header from oom_kill_process+0x230/0x2a0 Feb 11 23:04:18 volumiopi4 kernel: oom_kill_process from out_of_memory+0x104/0x34c Feb 11 23:04:18 volumiopi4 kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Feb 11 23:04:18 volumiopi4 kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Feb 11 23:04:18 volumiopi4 kernel: new_slab from ___slab_alloc+0x570/0x958 Feb 11 23:04:18 volumiopi4 kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Feb 11 23:04:18 volumiopi4 kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Feb 11 23:04:18 volumiopi4 kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Feb 11 23:04:18 volumiopi4 kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Feb 11 23:04:18 volumiopi4 kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Feb 11 23:04:18 volumiopi4 kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Feb 11 23:04:18 volumiopi4 kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Feb 11 23:04:18 volumiopi4 kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Feb 11 23:04:18 volumiopi4 kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Feb 11 23:04:18 volumiopi4 kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Feb 11 23:04:18 volumiopi4 kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Feb 11 23:04:18 volumiopi4 kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Feb 11 23:04:18 volumiopi4 kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Feb 11 23:04:18 volumiopi4 kernel: vfs_write from ksys_write+0x68/0xec Feb 11 23:04:18 volumiopi4 kernel: ksys_write from ret_fast_syscall+0x0/0x5c Feb 11 23:04:18 volumiopi4 kernel: Exception stack(0xf0af9fa8 to 0xf0af9ff0) Feb 11 23:04:18 volumiopi4 kernel: 9fa0: 02515d9d bebbb04c 0000000e aec72000 02515d9d 00000001 Feb 11 23:04:18 volumiopi4 kernel: 9fc0: 02515d9d bebbb04c 00000000 00000004 b6e2d4d4 b6a02584 bebbb130 bebbb110 Feb 11 23:04:18 volumiopi4 kernel: 9fe0: b6f1a710 bebbafe0 b6e27978 b6b047ac Feb 11 23:04:18 volumiopi4 kernel: Mem-Info: Feb 11 23:04:18 volumiopi4 kernel: active_anon:353 inactive_anon:63766 isolated_anon:0 active_file:53010 inactive_file:185767 isolated_file:0 unevictable:4092 dirty:9164 writeback:0 slab_reclaimable:18092 slab_unreclaimable:6271 mapped:51935 shmem:2034 pagetables:1611 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:628538 free_pcp:5 free_cma:125960 Feb 11 23:04:18 volumiopi4 kernel: Node 0 active_anon:1412kB inactive_anon:255064kB active_file:212040kB inactive_file:743068kB unevictable:16368kB isolated(anon):0kB isolated(file):0kB mapped:207740kB dirty:36656kB writeback:0kB shmem:8136kB writeback_tmp:0kB kernel_stack:2560kB pagetables:6444kB sec_pagetables:0kB all_unreclaimable? no Feb 11 23:04:18 volumiopi4 kernel: DMA free:532544kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:15088kB inactive_file:388kB unevictable:0kB writepending:756kB present:786432kB managed:662964kB mlocked:0kB bounce:0kB free_pcp:20kB local_pcp:0kB free_cma:503840kB Feb 11 23:04:18 volumiopi4 kernel: lowmem_reserve[]: 0 0 3136 3136 Feb 11 23:04:18 volumiopi4 kernel: DMA: 380*4kB (UE) 398*8kB (UEC) 217*16kB (UEC) 154*32kB (UEC) 90*64kB (UMEC) 54*128kB (UEC) 14*256kB (UC) 11*512kB (UMC) 4*1024kB (EC) 1*2048kB (C) 120*4096kB (C) = 532656kB Feb 11 23:04:18 volumiopi4 kernel: 242375 total pagecache pages Feb 11 23:04:18 volumiopi4 kernel: 0 pages in swap cache Feb 11 23:04:18 volumiopi4 kernel: Free swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: Total swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: 999424 pages RAM Feb 11 23:04:18 volumiopi4 kernel: 802816 pages HighMem/MovableOnly Feb 11 23:04:18 volumiopi4 kernel: 30867 pages reserved Feb 11 23:04:18 volumiopi4 kernel: 131072 pages cma reserved Feb 11 23:04:18 volumiopi4 kernel: Tasks state (memory values in pages): Feb 11 23:04:18 volumiopi4 kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Feb 11 23:04:18 volumiopi4 kernel: [ 463] 0 463 7671 2115 192 1443 480 65536 0 -250 systemd-journal Feb 11 23:04:18 volumiopi4 kernel: [ 485] 0 485 5991 1186 256 930 0 53248 0 -1000 systemd-udevd Feb 11 23:04:18 volumiopi4 kernel: [ 699] 0 699 1959 1130 765 365 0 40960 0 0 haveged Feb 11 23:04:18 volumiopi4 kernel: [ 702] 108 702 1731 773 64 709 0 40960 0 0 rpcbind Feb 11 23:04:18 volumiopi4 kernel: [ 756] 0 756 2740 1302 64 1238 0 45056 0 0 bluetoothd Feb 11 23:04:18 volumiopi4 kernel: [ 757] 101 757 1916 958 128 830 0 40960 0 -900 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 769] 0 769 7706 1652 160 1492 0 61440 0 0 systemd-logind Feb 11 23:04:18 volumiopi4 kernel: [ 770] 65534 770 1250 595 32 563 0 32768 0 0 thd Feb 11 23:04:18 volumiopi4 kernel: [ 774] 1000 774 11915 2596 316 2280 0 81920 0 0 volumio-remote- Feb 11 23:04:18 volumiopi4 kernel: [ 778] 0 778 32098 10047 1336 8711 0 258048 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 898] 100 898 639 431 64 367 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 899] 0 899 643 499 64 435 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 902] 100 902 560 278 32 246 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 903] 100 903 560 279 32 247 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 936] 0 936 3275 1336 96 1240 0 49152 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 940] 0 940 10966 1669 96 1573 0 69632 0 0 bluealsa Feb 11 23:04:18 volumiopi4 kernel: [ 1037] 0 1037 1409 646 32 614 0 36864 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1040] 0 1040 930 582 32 550 0 28672 0 0 ip Feb 11 23:04:18 volumiopi4 kernel: [ 1041] 0 1041 1393 461 32 429 0 32768 0 0 sed Feb 11 23:04:18 volumiopi4 kernel: [ 1042] 0 1042 1409 416 33 383 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1044] 106 1044 4360 4025 2454 1571 0 65536 0 0 ntpd Feb 11 23:04:18 volumiopi4 kernel: [ 1065] 0 1065 520 348 0 348 0 24576 0 0 ifplugd Feb 11 23:04:18 volumiopi4 kernel: [ 1072] 0 1072 3391 1970 192 1778 0 53248 0 -1000 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1119] 0 1119 16457 3211 416 2726 69 94208 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1123] 0 1123 16383 1024 406 618 0 86016 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1216] 0 1216 3393 1459 196 1263 0 53248 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 1237] 100 1237 643 296 33 263 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 1253] 1000 1253 75212 36920 24433 12487 0 1105920 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1273] 0 1273 1115 460 0 460 0 36864 0 0 agetty Feb 11 23:04:18 volumiopi4 kernel: [ 1317] 0 1317 18061 3947 480 3422 45 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1323] 0 1323 18121 3613 482 3088 43 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1354] 0 1354 19290 4326 512 3620 194 118784 0 0 smbd Feb 11 23:04:18 volumiopi4 kernel: [ 1355] 103 1355 1572 831 64 767 0 36864 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1356] 103 1356 1545 419 64 355 0 32768 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1380] 0 1380 18897 2658 501 2157 0 110592 0 0 smbd-notifyd Feb 11 23:04:18 volumiopi4 kernel: [ 1381] 0 1381 18897 1261 501 760 0 110592 0 0 cleanupd Feb 11 23:04:18 volumiopi4 kernel: [ 1386] 0 1386 18061 2676 485 2191 0 106496 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1403] 0 1403 3669 2242 224 2018 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1410] 1000 1410 4062 2257 256 2001 0 61440 0 100 systemd Feb 11 23:04:18 volumiopi4 kernel: [ 1411] 1000 1411 8898 1095 598 497 0 65536 0 100 (sd-pam) Feb 11 23:04:18 volumiopi4 kernel: [ 1432] 1000 1432 1400 631 32 599 0 36864 0 0 network_monitor Feb 11 23:04:18 volumiopi4 kernel: [ 1465] 1000 1465 3734 1569 311 1258 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1472] 1000 1472 580 421 32 389 0 28672 0 0 sftp-server Feb 11 23:04:18 volumiopi4 kernel: [ 1485] 1000 1485 31905 8495 931 7564 0 233472 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1495] 1000 1495 33078 11486 2226 9260 0 307200 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1496] 1000 1496 32925 11523 2225 9298 0 303104 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1497] 1000 1497 32989 11517 2202 9315 0 311296 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1562] 0 1562 161161 5997 1554 4443 0 155648 0 0 volumio5-onboar Feb 11 23:04:18 volumiopi4 kernel: [ 1597] 102 1597 119125 23055 5916 17139 0 643072 0 0 mpd Feb 11 23:04:18 volumiopi4 kernel: [ 1664] 104 1664 37337 2884 384 2500 0 143360 0 0 shairport-sync Feb 11 23:04:18 volumiopi4 kernel: [ 1736] 1000 1736 31956 3009 288 2721 0 118784 0 0 upmpdcli Feb 11 23:04:18 volumiopi4 kernel: [ 2043] 0 2043 3669 2240 224 2016 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2068] 1000 2068 3734 1569 311 1258 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2069] 1000 2069 983 846 224 622 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 3529] 1000 3529 515 311 0 311 0 28672 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 3531] 1000 3531 2017 921 64 857 0 45056 0 0 sudo Feb 11 23:04:18 volumiopi4 kernel: [ 3532] 0 3532 515 322 0 322 0 32768 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 5781] 0 5781 24793 23406 12105 11301 0 229376 0 0 apt-get Feb 11 23:04:18 volumiopi4 kernel: [ 5906] 1000 5906 1115 349 0 349 0 32768 0 0 sleep Feb 11 23:04:18 volumiopi4 kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=mpris-proxy,pid=1426,uid=1000 Feb 11 23:04:18 volumiopi4 kernel: Out of memory: OOM victim 1426 (mpris-proxy) is already exiting. Skip killing the task Feb 11 23:04:18 volumiopi4 kernel: apt-get invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Feb 11 23:04:18 volumiopi4 kernel: CPU: 2 UID: 0 PID: 5781 Comm: apt-get Tainted: G C 6.12.47-v7l+ #1904 Feb 11 23:04:18 volumiopi4 kernel: Tainted: [C]=CRAP Feb 11 23:04:18 volumiopi4 kernel: Hardware name: BCM2711 Feb 11 23:04:18 volumiopi4 kernel: Call trace: Feb 11 23:04:18 volumiopi4 kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 11 23:04:18 volumiopi4 kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 11 23:04:18 volumiopi4 kernel: dump_stack_lvl from dump_header+0x50/0x180 Feb 11 23:04:18 volumiopi4 kernel: dump_header from oom_kill_process+0x230/0x2a0 Feb 11 23:04:18 volumiopi4 kernel: oom_kill_process from out_of_memory+0x104/0x34c Feb 11 23:04:18 volumiopi4 kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Feb 11 23:04:18 volumiopi4 kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Feb 11 23:04:18 volumiopi4 kernel: new_slab from ___slab_alloc+0x570/0x958 Feb 11 23:04:18 volumiopi4 kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Feb 11 23:04:18 volumiopi4 kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Feb 11 23:04:18 volumiopi4 kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Feb 11 23:04:18 volumiopi4 kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Feb 11 23:04:18 volumiopi4 kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Feb 11 23:04:18 volumiopi4 kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Feb 11 23:04:18 volumiopi4 kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Feb 11 23:04:18 volumiopi4 kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Feb 11 23:04:18 volumiopi4 kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Feb 11 23:04:18 volumiopi4 kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Feb 11 23:04:18 volumiopi4 kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Feb 11 23:04:18 volumiopi4 kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Feb 11 23:04:18 volumiopi4 kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Feb 11 23:04:18 volumiopi4 kernel: vfs_write from ksys_write+0x68/0xec Feb 11 23:04:18 volumiopi4 kernel: ksys_write from ret_fast_syscall+0x0/0x5c Feb 11 23:04:18 volumiopi4 kernel: Exception stack(0xf0af9fa8 to 0xf0af9ff0) Feb 11 23:04:18 volumiopi4 kernel: 9fa0: 02515d9d bebbb04c 0000000e aec72000 02515d9d 00000001 Feb 11 23:04:18 volumiopi4 kernel: 9fc0: 02515d9d bebbb04c 00000000 00000004 b6e2d4d4 b6a02584 bebbb130 bebbb110 Feb 11 23:04:18 volumiopi4 kernel: 9fe0: b6f1a710 bebbafe0 b6e27978 b6b047ac Feb 11 23:04:18 volumiopi4 kernel: Mem-Info: Feb 11 23:04:18 volumiopi4 kernel: active_anon:353 inactive_anon:63692 isolated_anon:0 active_file:52812 inactive_file:185677 isolated_file:0 unevictable:4092 dirty:9164 writeback:0 slab_reclaimable:18092 slab_unreclaimable:6271 mapped:51898 shmem:2034 pagetables:1611 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:628916 free_pcp:2 free_cma:125960 Feb 11 23:04:18 volumiopi4 kernel: Node 0 active_anon:1412kB inactive_anon:254768kB active_file:211248kB inactive_file:742708kB unevictable:16368kB isolated(anon):0kB isolated(file):0kB mapped:207592kB dirty:36656kB writeback:0kB shmem:8136kB writeback_tmp:0kB kernel_stack:2560kB pagetables:6444kB sec_pagetables:0kB all_unreclaimable? no Feb 11 23:04:18 volumiopi4 kernel: DMA free:532544kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:15088kB inactive_file:388kB unevictable:0kB writepending:756kB present:786432kB managed:662964kB mlocked:0kB bounce:0kB free_pcp:8kB local_pcp:0kB free_cma:503840kB Feb 11 23:04:18 volumiopi4 kernel: lowmem_reserve[]: 0 0 3136 3136 Feb 11 23:04:18 volumiopi4 kernel: DMA: 381*4kB (UE) 398*8kB (UEC) 216*16kB (UEC) 155*32kB (UEC) 91*64kB (UMEC) 54*128kB (UEC) 14*256kB (UC) 11*512kB (UMC) 4*1024kB (EC) 1*2048kB (C) 120*4096kB (C) = 532740kB Feb 11 23:04:18 volumiopi4 kernel: 242153 total pagecache pages Feb 11 23:04:18 volumiopi4 kernel: 0 pages in swap cache Feb 11 23:04:18 volumiopi4 kernel: Free swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: Total swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: 999424 pages RAM Feb 11 23:04:18 volumiopi4 kernel: 802816 pages HighMem/MovableOnly Feb 11 23:04:18 volumiopi4 kernel: 30867 pages reserved Feb 11 23:04:18 volumiopi4 kernel: 131072 pages cma reserved Feb 11 23:04:18 volumiopi4 kernel: Tasks state (memory values in pages): Feb 11 23:04:18 volumiopi4 kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Feb 11 23:04:18 volumiopi4 kernel: [ 463] 0 463 7671 2115 192 1443 480 65536 0 -250 systemd-journal Feb 11 23:04:18 volumiopi4 kernel: [ 485] 0 485 5991 1186 256 930 0 53248 0 -1000 systemd-udevd Feb 11 23:04:18 volumiopi4 kernel: [ 699] 0 699 1959 1130 765 365 0 40960 0 0 haveged Feb 11 23:04:18 volumiopi4 kernel: [ 702] 108 702 1731 773 64 709 0 40960 0 0 rpcbind Feb 11 23:04:18 volumiopi4 kernel: [ 756] 0 756 2740 1302 64 1238 0 45056 0 0 bluetoothd Feb 11 23:04:18 volumiopi4 kernel: [ 757] 101 757 1916 958 128 830 0 40960 0 -900 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 769] 0 769 7706 1652 160 1492 0 61440 0 0 systemd-logind Feb 11 23:04:18 volumiopi4 kernel: [ 770] 65534 770 1250 595 32 563 0 32768 0 0 thd Feb 11 23:04:18 volumiopi4 kernel: [ 774] 1000 774 11915 2596 316 2280 0 81920 0 0 volumio-remote- Feb 11 23:04:18 volumiopi4 kernel: [ 778] 0 778 32098 10047 1336 8711 0 258048 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 898] 100 898 639 431 64 367 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 899] 0 899 643 499 64 435 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 902] 100 902 560 278 32 246 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 903] 100 903 560 279 32 247 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 936] 0 936 3275 1336 96 1240 0 49152 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 940] 0 940 10966 1669 96 1573 0 69632 0 0 bluealsa Feb 11 23:04:18 volumiopi4 kernel: [ 1037] 0 1037 1409 646 32 614 0 36864 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1040] 0 1040 930 582 32 550 0 28672 0 0 ip Feb 11 23:04:18 volumiopi4 kernel: [ 1041] 0 1041 1393 461 32 429 0 32768 0 0 sed Feb 11 23:04:18 volumiopi4 kernel: [ 1042] 0 1042 1409 416 33 383 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1044] 106 1044 4360 4025 2454 1571 0 65536 0 0 ntpd Feb 11 23:04:18 volumiopi4 kernel: [ 1065] 0 1065 520 348 0 348 0 24576 0 0 ifplugd Feb 11 23:04:18 volumiopi4 kernel: [ 1072] 0 1072 3391 1970 192 1778 0 53248 0 -1000 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1119] 0 1119 16457 3211 416 2726 69 94208 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1123] 0 1123 16383 1024 406 618 0 86016 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1216] 0 1216 3393 1459 196 1263 0 53248 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 1237] 100 1237 643 296 33 263 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 1253] 1000 1253 75212 36920 24433 12487 0 1105920 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1273] 0 1273 1115 460 0 460 0 36864 0 0 agetty Feb 11 23:04:18 volumiopi4 kernel: [ 1317] 0 1317 18061 3947 480 3422 45 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1323] 0 1323 18121 3613 482 3088 43 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1354] 0 1354 19290 4326 512 3620 194 118784 0 0 smbd Feb 11 23:04:18 volumiopi4 kernel: [ 1355] 103 1355 1572 831 64 767 0 36864 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1356] 103 1356 1545 419 64 355 0 32768 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1380] 0 1380 18897 2658 501 2157 0 110592 0 0 smbd-notifyd Feb 11 23:04:18 volumiopi4 kernel: [ 1381] 0 1381 18897 1261 501 760 0 110592 0 0 cleanupd Feb 11 23:04:18 volumiopi4 kernel: [ 1386] 0 1386 18061 2676 485 2191 0 106496 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1403] 0 1403 3669 2242 224 2018 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1410] 1000 1410 4062 2257 256 2001 0 61440 0 100 systemd Feb 11 23:04:18 volumiopi4 kernel: [ 1411] 1000 1411 8898 1095 598 497 0 65536 0 100 (sd-pam) Feb 11 23:04:18 volumiopi4 kernel: [ 1432] 1000 1432 1400 631 32 599 0 36864 0 0 network_monitor Feb 11 23:04:18 volumiopi4 kernel: [ 1465] 1000 1465 3734 1569 311 1258 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1472] 1000 1472 580 421 32 389 0 28672 0 0 sftp-server Feb 11 23:04:18 volumiopi4 kernel: [ 1485] 1000 1485 31905 8495 931 7564 0 233472 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1495] 1000 1495 33078 11486 2226 9260 0 307200 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1496] 1000 1496 32925 11523 2225 9298 0 303104 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1497] 1000 1497 32989 11517 2202 9315 0 311296 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1562] 0 1562 161161 5997 1554 4443 0 155648 0 0 volumio5-onboar Feb 11 23:04:18 volumiopi4 kernel: [ 1597] 102 1597 119125 23055 5916 17139 0 643072 0 0 mpd Feb 11 23:04:18 volumiopi4 kernel: [ 1664] 104 1664 37337 2884 384 2500 0 143360 0 0 shairport-sync Feb 11 23:04:18 volumiopi4 kernel: [ 1736] 1000 1736 31956 3009 288 2721 0 118784 0 0 upmpdcli Feb 11 23:04:18 volumiopi4 kernel: [ 2043] 0 2043 3669 2240 224 2016 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2068] 1000 2068 3734 1569 311 1258 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2069] 1000 2069 983 846 224 622 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 3529] 1000 3529 515 311 0 311 0 28672 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 3531] 1000 3531 2017 921 64 857 0 45056 0 0 sudo Feb 11 23:04:18 volumiopi4 kernel: [ 3532] 0 3532 515 322 0 322 0 32768 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 5781] 0 5781 24793 23406 12105 11301 0 229376 0 0 apt-get Feb 11 23:04:18 volumiopi4 kernel: [ 5906] 1000 5906 1115 349 0 349 0 32768 0 0 sleep Feb 11 23:04:18 volumiopi4 kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=systemd,pid=1410,uid=1000 Feb 11 23:04:18 volumiopi4 kernel: Out of memory: Killed process 1410 (systemd) total-vm:16248kB, anon-rss:1024kB, file-rss:8004kB, shmem-rss:0kB, UID:1000 pgtables:60kB oom_score_adj:100 Feb 11 23:04:18 volumiopi4 kernel: apt-get invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Feb 11 23:04:18 volumiopi4 kernel: CPU: 2 UID: 0 PID: 5781 Comm: apt-get Tainted: G C 6.12.47-v7l+ #1904 Feb 11 23:04:18 volumiopi4 kernel: Tainted: [C]=CRAP Feb 11 23:04:18 volumiopi4 kernel: Hardware name: BCM2711 Feb 11 23:04:18 volumiopi4 kernel: Call trace: Feb 11 23:04:18 volumiopi4 kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 11 23:04:18 volumiopi4 kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 11 23:04:18 volumiopi4 kernel: dump_stack_lvl from dump_header+0x50/0x180 Feb 11 23:04:18 volumiopi4 kernel: dump_header from oom_kill_process+0x230/0x2a0 Feb 11 23:04:18 volumiopi4 kernel: oom_kill_process from out_of_memory+0x104/0x34c Feb 11 23:04:18 volumiopi4 kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Feb 11 23:04:18 volumiopi4 kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Feb 11 23:04:18 volumiopi4 kernel: new_slab from ___slab_alloc+0x570/0x958 Feb 11 23:04:18 volumiopi4 kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Feb 11 23:04:18 volumiopi4 kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Feb 11 23:04:18 volumiopi4 kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Feb 11 23:04:18 volumiopi4 kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Feb 11 23:04:18 volumiopi4 kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Feb 11 23:04:18 volumiopi4 kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Feb 11 23:04:18 volumiopi4 kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Feb 11 23:04:18 volumiopi4 kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Feb 11 23:04:18 volumiopi4 kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Feb 11 23:04:18 volumiopi4 kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Feb 11 23:04:18 volumiopi4 kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Feb 11 23:04:18 volumiopi4 kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Feb 11 23:04:18 volumiopi4 kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Feb 11 23:04:18 volumiopi4 kernel: vfs_write from ksys_write+0x68/0xec Feb 11 23:04:18 volumiopi4 kernel: ksys_write from ret_fast_syscall+0x0/0x5c Feb 11 23:04:18 volumiopi4 kernel: Exception stack(0xf0af9fa8 to 0xf0af9ff0) Feb 11 23:04:18 volumiopi4 kernel: 9fa0: 02515d9d bebbb04c 0000000e aec72000 02515d9d 00000001 Feb 11 23:04:18 volumiopi4 kernel: 9fc0: 02515d9d bebbb04c 00000000 00000004 b6e2d4d4 b6a02584 bebbb130 bebbb110 Feb 11 23:04:18 volumiopi4 kernel: 9fe0: b6f1a710 bebbafe0 b6e27978 b6b047ac Feb 11 23:04:18 volumiopi4 kernel: Mem-Info: Feb 11 23:04:18 volumiopi4 kernel: active_anon:353 inactive_anon:63396 isolated_anon:0 active_file:52642 inactive_file:185587 isolated_file:0 unevictable:4092 dirty:9164 writeback:0 slab_reclaimable:18092 slab_unreclaimable:6271 mapped:51898 shmem:2034 pagetables:1611 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:629042 free_pcp:11 free_cma:125960 Feb 11 23:04:18 volumiopi4 kernel: Node 0 active_anon:1412kB inactive_anon:253584kB active_file:210568kB inactive_file:742348kB unevictable:16368kB isolated(anon):0kB isolated(file):0kB mapped:207592kB dirty:36656kB writeback:0kB shmem:8136kB writeback_tmp:0kB kernel_stack:2560kB pagetables:6444kB sec_pagetables:0kB all_unreclaimable? no Feb 11 23:04:18 volumiopi4 kernel: DMA free:532040kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:15088kB inactive_file:388kB unevictable:0kB writepending:756kB present:786432kB managed:662964kB mlocked:0kB bounce:0kB free_pcp:44kB local_pcp:0kB free_cma:503840kB Feb 11 23:04:18 volumiopi4 kernel: lowmem_reserve[]: 0 0 3136 3136 Feb 11 23:04:18 volumiopi4 kernel: DMA: 381*4kB (UE) 399*8kB (UEC) 216*16kB (UEC) 154*32kB (UEC) 90*64kB (UMEC) 55*128kB (UEC) 14*256kB (UC) 11*512kB (UMC) 4*1024kB (EC) 1*2048kB (C) 120*4096kB (C) = 532780kB Feb 11 23:04:18 volumiopi4 kernel: 241894 total pagecache pages Feb 11 23:04:18 volumiopi4 kernel: 0 pages in swap cache Feb 11 23:04:18 volumiopi4 kernel: Free swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: Total swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: 999424 pages RAM Feb 11 23:04:18 volumiopi4 kernel: 802816 pages HighMem/MovableOnly Feb 11 23:04:18 volumiopi4 kernel: 30867 pages reserved Feb 11 23:04:18 volumiopi4 kernel: 131072 pages cma reserved Feb 11 23:04:18 volumiopi4 kernel: Tasks state (memory values in pages): Feb 11 23:04:18 volumiopi4 kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Feb 11 23:04:18 volumiopi4 kernel: [ 463] 0 463 7671 2115 192 1443 480 65536 0 -250 systemd-journal Feb 11 23:04:18 volumiopi4 kernel: [ 485] 0 485 5991 1186 256 930 0 53248 0 -1000 systemd-udevd Feb 11 23:04:18 volumiopi4 kernel: [ 699] 0 699 1959 1130 765 365 0 40960 0 0 haveged Feb 11 23:04:18 volumiopi4 kernel: [ 702] 108 702 1731 773 64 709 0 40960 0 0 rpcbind Feb 11 23:04:18 volumiopi4 kernel: [ 756] 0 756 2740 1302 64 1238 0 45056 0 0 bluetoothd Feb 11 23:04:18 volumiopi4 kernel: [ 757] 101 757 1916 958 128 830 0 40960 0 -900 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 769] 0 769 7706 1652 160 1492 0 61440 0 0 systemd-logind Feb 11 23:04:18 volumiopi4 kernel: [ 770] 65534 770 1250 595 32 563 0 32768 0 0 thd Feb 11 23:04:18 volumiopi4 kernel: [ 774] 1000 774 11915 2596 316 2280 0 81920 0 0 volumio-remote- Feb 11 23:04:18 volumiopi4 kernel: [ 778] 0 778 32098 10047 1336 8711 0 258048 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 898] 100 898 639 431 64 367 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 899] 0 899 643 499 64 435 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 902] 100 902 560 278 32 246 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 903] 100 903 560 279 32 247 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 936] 0 936 3275 1336 96 1240 0 49152 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 940] 0 940 10966 1669 96 1573 0 69632 0 0 bluealsa Feb 11 23:04:18 volumiopi4 kernel: [ 1037] 0 1037 1409 646 32 614 0 36864 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1040] 0 1040 930 582 32 550 0 28672 0 0 ip Feb 11 23:04:18 volumiopi4 kernel: [ 1041] 0 1041 1393 461 32 429 0 32768 0 0 sed Feb 11 23:04:18 volumiopi4 kernel: [ 1042] 0 1042 1409 416 33 383 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1044] 106 1044 4360 4025 2454 1571 0 65536 0 0 ntpd Feb 11 23:04:18 volumiopi4 kernel: [ 1065] 0 1065 520 348 0 348 0 24576 0 0 ifplugd Feb 11 23:04:18 volumiopi4 kernel: [ 1072] 0 1072 3391 1970 192 1778 0 53248 0 -1000 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1119] 0 1119 16457 3211 416 2726 69 94208 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1123] 0 1123 16383 1024 406 618 0 86016 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1216] 0 1216 3393 1459 196 1263 0 53248 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 1237] 100 1237 643 296 33 263 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 1253] 1000 1253 75212 36920 24433 12487 0 1105920 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1273] 0 1273 1115 460 0 460 0 36864 0 0 agetty Feb 11 23:04:18 volumiopi4 kernel: [ 1317] 0 1317 18061 3947 480 3422 45 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1323] 0 1323 18121 3613 482 3088 43 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1354] 0 1354 19290 4326 512 3620 194 118784 0 0 smbd Feb 11 23:04:18 volumiopi4 kernel: [ 1355] 103 1355 1572 831 64 767 0 36864 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1356] 103 1356 1545 419 64 355 0 32768 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1380] 0 1380 18897 2658 501 2157 0 110592 0 0 smbd-notifyd Feb 11 23:04:18 volumiopi4 kernel: [ 1381] 0 1381 18897 1261 501 760 0 110592 0 0 cleanupd Feb 11 23:04:18 volumiopi4 kernel: [ 1386] 0 1386 18061 2676 485 2191 0 106496 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1403] 0 1403 3669 2242 224 2018 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1411] 1000 1411 8898 1095 598 497 0 65536 0 100 (sd-pam) Feb 11 23:04:18 volumiopi4 kernel: [ 1432] 1000 1432 1400 631 32 599 0 36864 0 0 network_monitor Feb 11 23:04:18 volumiopi4 kernel: [ 1465] 1000 1465 3734 1569 311 1258 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1472] 1000 1472 580 421 32 389 0 28672 0 0 sftp-server Feb 11 23:04:18 volumiopi4 kernel: [ 1485] 1000 1485 31905 8495 931 7564 0 233472 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1495] 1000 1495 33078 11486 2226 9260 0 307200 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1496] 1000 1496 32925 11523 2225 9298 0 303104 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1497] 1000 1497 32989 11517 2202 9315 0 311296 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1562] 0 1562 161161 5997 1554 4443 0 155648 0 0 volumio5-onboar Feb 11 23:04:18 volumiopi4 kernel: [ 1597] 102 1597 119125 23055 5916 17139 0 643072 0 0 mpd Feb 11 23:04:18 volumiopi4 kernel: [ 1664] 104 1664 37337 2884 384 2500 0 143360 0 0 shairport-sync Feb 11 23:04:18 volumiopi4 kernel: [ 1736] 1000 1736 31956 3009 288 2721 0 118784 0 0 upmpdcli Feb 11 23:04:18 volumiopi4 kernel: [ 2043] 0 2043 3669 2240 224 2016 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2068] 1000 2068 3734 1569 311 1258 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2069] 1000 2069 983 846 224 622 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 3529] 1000 3529 515 311 0 311 0 28672 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 3531] 1000 3531 2017 921 64 857 0 45056 0 0 sudo Feb 11 23:04:18 volumiopi4 kernel: [ 3532] 0 3532 515 322 0 322 0 32768 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 5781] 0 5781 24793 23406 12105 11301 0 229376 0 0 apt-get Feb 11 23:04:18 volumiopi4 kernel: [ 5906] 1000 5906 1115 349 0 349 0 32768 0 0 sleep Feb 11 23:04:18 volumiopi4 kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=(sd-pam),pid=1411,uid=1000 Feb 11 23:04:18 volumiopi4 kernel: Out of memory: Killed process 1411 ((sd-pam)) total-vm:35592kB, anon-rss:2392kB, file-rss:1988kB, shmem-rss:0kB, UID:1000 pgtables:64kB oom_score_adj:100 Feb 11 23:04:18 volumiopi4 kernel: apt-get invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Feb 11 23:04:18 volumiopi4 kernel: CPU: 2 UID: 0 PID: 5781 Comm: apt-get Tainted: G C 6.12.47-v7l+ #1904 Feb 11 23:04:18 volumiopi4 kernel: Tainted: [C]=CRAP Feb 11 23:04:18 volumiopi4 kernel: Hardware name: BCM2711 Feb 11 23:04:18 volumiopi4 kernel: Call trace: Feb 11 23:04:18 volumiopi4 kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 11 23:04:18 volumiopi4 kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 11 23:04:18 volumiopi4 kernel: dump_stack_lvl from dump_header+0x50/0x180 Feb 11 23:04:18 volumiopi4 kernel: dump_header from oom_kill_process+0x230/0x2a0 Feb 11 23:04:18 volumiopi4 kernel: oom_kill_process from out_of_memory+0x104/0x34c Feb 11 23:04:18 volumiopi4 kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Feb 11 23:04:18 volumiopi4 kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Feb 11 23:04:18 volumiopi4 kernel: new_slab from ___slab_alloc+0x570/0x958 Feb 11 23:04:18 volumiopi4 kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Feb 11 23:04:18 volumiopi4 kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Feb 11 23:04:18 volumiopi4 kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Feb 11 23:04:18 volumiopi4 kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Feb 11 23:04:18 volumiopi4 kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Feb 11 23:04:18 volumiopi4 kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Feb 11 23:04:18 volumiopi4 kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Feb 11 23:04:18 volumiopi4 kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Feb 11 23:04:18 volumiopi4 kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Feb 11 23:04:18 volumiopi4 kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Feb 11 23:04:18 volumiopi4 kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Feb 11 23:04:18 volumiopi4 kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Feb 11 23:04:18 volumiopi4 kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Feb 11 23:04:18 volumiopi4 kernel: vfs_write from ksys_write+0x68/0xec Feb 11 23:04:18 volumiopi4 kernel: ksys_write from ret_fast_syscall+0x0/0x5c Feb 11 23:04:18 volumiopi4 kernel: Exception stack(0xf0af9fa8 to 0xf0af9ff0) Feb 11 23:04:18 volumiopi4 kernel: 9fa0: 02515d9d bebbb04c 0000000e aec72000 02515d9d 00000001 Feb 11 23:04:18 volumiopi4 kernel: 9fc0: 02515d9d bebbb04c 00000000 00000004 b6e2d4d4 b6a02584 bebbb130 bebbb110 Feb 11 23:04:18 volumiopi4 kernel: 9fe0: b6f1a710 bebbafe0 b6e27978 b6b047ac Feb 11 23:04:18 volumiopi4 kernel: Mem-Info: Feb 11 23:04:18 volumiopi4 kernel: active_anon:353 inactive_anon:62989 isolated_anon:0 active_file:52423 inactive_file:185428 isolated_file:0 unevictable:4092 dirty:9164 writeback:0 slab_reclaimable:18055 slab_unreclaimable:6271 mapped:51898 shmem:2034 pagetables:1611 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:630176 free_pcp:8 free_cma:125960 Feb 11 23:04:18 volumiopi4 kernel: Node 0 active_anon:1412kB inactive_anon:251956kB active_file:209692kB inactive_file:741712kB unevictable:16368kB isolated(anon):0kB isolated(file):0kB mapped:207592kB dirty:36656kB writeback:0kB shmem:8136kB writeback_tmp:0kB kernel_stack:2560kB pagetables:6444kB sec_pagetables:0kB all_unreclaimable? no Feb 11 23:04:18 volumiopi4 kernel: DMA free:532544kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:15088kB inactive_file:388kB unevictable:0kB writepending:756kB present:786432kB managed:662964kB mlocked:0kB bounce:0kB free_pcp:48kB local_pcp:0kB free_cma:503840kB Feb 11 23:04:18 volumiopi4 kernel: lowmem_reserve[]: 0 0 3136 3136 Feb 11 23:04:18 volumiopi4 kernel: DMA: 383*4kB (UE) 399*8kB (UEC) 217*16kB (UEC) 156*32kB (UEC) 90*64kB (UMEC) 55*128kB (UEC) 14*256kB (UC) 11*512kB (UMC) 4*1024kB (EC) 1*2048kB (C) 120*4096kB (C) = 532868kB Feb 11 23:04:18 volumiopi4 kernel: 241450 total pagecache pages Feb 11 23:04:18 volumiopi4 kernel: 0 pages in swap cache Feb 11 23:04:18 volumiopi4 kernel: Free swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: Total swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: 999424 pages RAM Feb 11 23:04:18 volumiopi4 kernel: 802816 pages HighMem/MovableOnly Feb 11 23:04:18 volumiopi4 kernel: 30867 pages reserved Feb 11 23:04:18 volumiopi4 kernel: 131072 pages cma reserved Feb 11 23:04:18 volumiopi4 kernel: Tasks state (memory values in pages): Feb 11 23:04:18 volumiopi4 kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Feb 11 23:04:18 volumiopi4 kernel: [ 463] 0 463 7671 2115 192 1443 480 65536 0 -250 systemd-journal Feb 11 23:04:18 volumiopi4 kernel: [ 485] 0 485 5991 1186 256 930 0 53248 0 -1000 systemd-udevd Feb 11 23:04:18 volumiopi4 kernel: [ 699] 0 699 1959 1130 765 365 0 40960 0 0 haveged Feb 11 23:04:18 volumiopi4 kernel: [ 702] 108 702 1731 773 64 709 0 40960 0 0 rpcbind Feb 11 23:04:18 volumiopi4 kernel: [ 756] 0 756 2740 1302 64 1238 0 45056 0 0 bluetoothd Feb 11 23:04:18 volumiopi4 kernel: [ 757] 101 757 1916 958 128 830 0 40960 0 -900 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 769] 0 769 7706 1652 160 1492 0 61440 0 0 systemd-logind Feb 11 23:04:18 volumiopi4 kernel: [ 770] 65534 770 1250 595 32 563 0 32768 0 0 thd Feb 11 23:04:18 volumiopi4 kernel: [ 774] 1000 774 11915 2596 316 2280 0 81920 0 0 volumio-remote- Feb 11 23:04:18 volumiopi4 kernel: [ 778] 0 778 32098 10047 1336 8711 0 258048 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 898] 100 898 639 431 64 367 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 899] 0 899 643 499 64 435 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 902] 100 902 560 278 32 246 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 903] 100 903 560 279 32 247 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 936] 0 936 3275 1336 96 1240 0 49152 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 940] 0 940 10966 1669 96 1573 0 69632 0 0 bluealsa Feb 11 23:04:18 volumiopi4 kernel: [ 1037] 0 1037 1409 646 32 614 0 36864 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1040] 0 1040 930 582 32 550 0 28672 0 0 ip Feb 11 23:04:18 volumiopi4 kernel: [ 1041] 0 1041 1393 461 32 429 0 32768 0 0 sed Feb 11 23:04:18 volumiopi4 kernel: [ 1042] 0 1042 1409 416 33 383 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1044] 106 1044 4360 4025 2454 1571 0 65536 0 0 ntpd Feb 11 23:04:18 volumiopi4 kernel: [ 1065] 0 1065 520 348 0 348 0 24576 0 0 ifplugd Feb 11 23:04:18 volumiopi4 kernel: [ 1072] 0 1072 3391 1970 192 1778 0 53248 0 -1000 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1119] 0 1119 16457 3211 416 2726 69 94208 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1123] 0 1123 16383 1024 406 618 0 86016 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1216] 0 1216 3393 1459 196 1263 0 53248 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 1237] 100 1237 643 296 33 263 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 1253] 1000 1253 75212 36920 24433 12487 0 1105920 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1273] 0 1273 1115 460 0 460 0 36864 0 0 agetty Feb 11 23:04:18 volumiopi4 kernel: [ 1317] 0 1317 18061 3947 480 3422 45 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1323] 0 1323 18121 3613 482 3088 43 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1354] 0 1354 19290 4326 512 3620 194 118784 0 0 smbd Feb 11 23:04:18 volumiopi4 kernel: [ 1355] 103 1355 1572 831 64 767 0 36864 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1356] 103 1356 1545 419 64 355 0 32768 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1380] 0 1380 18897 2658 501 2157 0 110592 0 0 smbd-notifyd Feb 11 23:04:18 volumiopi4 kernel: [ 1381] 0 1381 18897 1261 501 760 0 110592 0 0 cleanupd Feb 11 23:04:18 volumiopi4 kernel: [ 1386] 0 1386 18061 2676 485 2191 0 106496 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1403] 0 1403 3669 2242 224 2018 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1432] 1000 1432 1400 631 32 599 0 36864 0 0 network_monitor Feb 11 23:04:18 volumiopi4 kernel: [ 1465] 1000 1465 3734 1569 311 1258 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1472] 1000 1472 580 421 32 389 0 28672 0 0 sftp-server Feb 11 23:04:18 volumiopi4 kernel: [ 1485] 1000 1485 31905 8495 931 7564 0 233472 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1495] 1000 1495 33078 11486 2226 9260 0 307200 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1496] 1000 1496 32925 11523 2225 9298 0 303104 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1497] 1000 1497 32989 11517 2202 9315 0 311296 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1562] 0 1562 161161 5997 1554 4443 0 155648 0 0 volumio5-onboar Feb 11 23:04:18 volumiopi4 kernel: [ 1597] 102 1597 119125 23055 5916 17139 0 643072 0 0 mpd Feb 11 23:04:18 volumiopi4 kernel: [ 1664] 104 1664 37337 2884 384 2500 0 143360 0 0 shairport-sync Feb 11 23:04:18 volumiopi4 kernel: [ 1736] 1000 1736 31956 3009 288 2721 0 118784 0 0 upmpdcli Feb 11 23:04:18 volumiopi4 kernel: [ 2043] 0 2043 3669 2240 224 2016 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2068] 1000 2068 3734 1569 311 1258 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2069] 1000 2069 983 846 224 622 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 3529] 1000 3529 515 311 0 311 0 28672 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 3531] 1000 3531 2017 921 64 857 0 45056 0 0 sudo Feb 11 23:04:18 volumiopi4 kernel: [ 3532] 0 3532 515 322 0 322 0 32768 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 5781] 0 5781 24793 23406 12105 11301 0 229376 0 0 apt-get Feb 11 23:04:18 volumiopi4 kernel: [ 5906] 1000 5906 1115 349 0 349 0 32768 0 0 sleep Feb 11 23:04:18 volumiopi4 kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=node,pid=1253,uid=1000 Feb 11 23:04:18 volumiopi4 kernel: Out of memory: Killed process 1253 (node) total-vm:300848kB, anon-rss:97732kB, file-rss:49948kB, shmem-rss:0kB, UID:1000 pgtables:1080kB oom_score_adj:0 Feb 11 23:04:18 volumiopi4 kernel: apt-get invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Feb 11 23:04:18 volumiopi4 kernel: CPU: 2 UID: 0 PID: 5781 Comm: apt-get Tainted: G C 6.12.47-v7l+ #1904 Feb 11 23:04:18 volumiopi4 kernel: Tainted: [C]=CRAP Feb 11 23:04:18 volumiopi4 kernel: Hardware name: BCM2711 Feb 11 23:04:18 volumiopi4 kernel: Call trace: Feb 11 23:04:18 volumiopi4 kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 11 23:04:18 volumiopi4 kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 11 23:04:18 volumiopi4 kernel: dump_stack_lvl from dump_header+0x50/0x180 Feb 11 23:04:18 volumiopi4 kernel: dump_header from oom_kill_process+0x230/0x2a0 Feb 11 23:04:18 volumiopi4 kernel: oom_kill_process from out_of_memory+0x104/0x34c Feb 11 23:04:18 volumiopi4 kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Feb 11 23:04:18 volumiopi4 kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Feb 11 23:04:18 volumiopi4 kernel: new_slab from ___slab_alloc+0x570/0x958 Feb 11 23:04:18 volumiopi4 kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Feb 11 23:04:18 volumiopi4 kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Feb 11 23:04:18 volumiopi4 kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Feb 11 23:04:18 volumiopi4 kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Feb 11 23:04:18 volumiopi4 kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Feb 11 23:04:18 volumiopi4 kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Feb 11 23:04:18 volumiopi4 kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Feb 11 23:04:18 volumiopi4 kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Feb 11 23:04:18 volumiopi4 kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Feb 11 23:04:18 volumiopi4 kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Feb 11 23:04:18 volumiopi4 kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Feb 11 23:04:18 volumiopi4 kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Feb 11 23:04:18 volumiopi4 kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Feb 11 23:04:18 volumiopi4 kernel: vfs_write from ksys_write+0x68/0xec Feb 11 23:04:18 volumiopi4 kernel: ksys_write from ret_fast_syscall+0x0/0x5c Feb 11 23:04:18 volumiopi4 kernel: Exception stack(0xf0af9fa8 to 0xf0af9ff0) Feb 11 23:04:18 volumiopi4 kernel: 9fa0: 02515d9d bebbb04c 0000000e aec72000 02515d9d 00000001 Feb 11 23:04:18 volumiopi4 kernel: 9fc0: 02515d9d bebbb04c 00000000 00000004 b6e2d4d4 b6a02584 bebbb130 bebbb110 Feb 11 23:04:18 volumiopi4 kernel: 9fe0: b6f1a710 bebbafe0 b6e27978 b6b047ac Feb 11 23:04:18 volumiopi4 kernel: Mem-Info: Feb 11 23:04:18 volumiopi4 kernel: active_anon:353 inactive_anon:38569 isolated_anon:0 active_file:52460 inactive_file:174407 isolated_file:0 unevictable:4092 dirty:9164 writeback:0 slab_reclaimable:17463 slab_unreclaimable:6271 mapped:49271 shmem:2034 pagetables:1389 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:666086 free_pcp:17 free_cma:125960 Feb 11 23:04:18 volumiopi4 kernel: Node 0 active_anon:1412kB inactive_anon:154276kB active_file:209840kB inactive_file:697628kB unevictable:16368kB isolated(anon):0kB isolated(file):0kB mapped:197084kB dirty:36656kB writeback:0kB shmem:8136kB writeback_tmp:0kB kernel_stack:2440kB pagetables:5556kB sec_pagetables:0kB all_unreclaimable? no Feb 11 23:04:18 volumiopi4 kernel: DMA free:535064kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:15088kB inactive_file:388kB unevictable:0kB writepending:756kB present:786432kB managed:662964kB mlocked:0kB bounce:0kB free_pcp:84kB local_pcp:0kB free_cma:503840kB Feb 11 23:04:18 volumiopi4 kernel: lowmem_reserve[]: 0 0 3136 3136 Feb 11 23:04:18 volumiopi4 kernel: DMA: 389*4kB (UE) 421*8kB (UEC) 237*16kB (UEC) 164*32kB (UEC) 94*64kB (UMEC) 61*128kB (UEC) 14*256kB (UC) 12*512kB (UMEC) 4*1024kB (EC) 1*2048kB (C) 120*4096kB (C) = 535180kB Feb 11 23:04:18 volumiopi4 kernel: 230498 total pagecache pages Feb 11 23:04:18 volumiopi4 kernel: 0 pages in swap cache Feb 11 23:04:18 volumiopi4 kernel: Free swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: Total swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: 999424 pages RAM Feb 11 23:04:18 volumiopi4 kernel: 802816 pages HighMem/MovableOnly Feb 11 23:04:18 volumiopi4 kernel: 30867 pages reserved Feb 11 23:04:18 volumiopi4 kernel: 131072 pages cma reserved Feb 11 23:04:18 volumiopi4 kernel: Tasks state (memory values in pages): Feb 11 23:04:18 volumiopi4 kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Feb 11 23:04:18 volumiopi4 kernel: [ 463] 0 463 7671 2147 192 1443 512 65536 0 -250 systemd-journal Feb 11 23:04:18 volumiopi4 kernel: [ 485] 0 485 5991 1186 256 930 0 53248 0 -1000 systemd-udevd Feb 11 23:04:18 volumiopi4 kernel: [ 699] 0 699 1959 1130 765 365 0 40960 0 0 haveged Feb 11 23:04:18 volumiopi4 kernel: [ 702] 108 702 1731 773 64 709 0 40960 0 0 rpcbind Feb 11 23:04:18 volumiopi4 kernel: [ 756] 0 756 2740 1302 64 1238 0 45056 0 0 bluetoothd Feb 11 23:04:18 volumiopi4 kernel: [ 757] 101 757 1916 958 128 830 0 40960 0 -900 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 769] 0 769 7706 1652 160 1492 0 61440 0 0 systemd-logind Feb 11 23:04:18 volumiopi4 kernel: [ 770] 65534 770 1250 595 32 563 0 32768 0 0 thd Feb 11 23:04:18 volumiopi4 kernel: [ 774] 1000 774 11915 2596 316 2280 0 81920 0 0 volumio-remote- Feb 11 23:04:18 volumiopi4 kernel: [ 778] 0 778 32098 10047 1336 8711 0 258048 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 898] 100 898 639 431 64 367 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 899] 0 899 643 499 64 435 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 902] 100 902 560 278 32 246 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 903] 100 903 560 279 32 247 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 936] 0 936 3275 1336 96 1240 0 49152 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 940] 0 940 10966 1669 96 1573 0 69632 0 0 bluealsa Feb 11 23:04:18 volumiopi4 kernel: [ 1037] 0 1037 1409 646 32 614 0 36864 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1040] 0 1040 930 582 32 550 0 28672 0 0 ip Feb 11 23:04:18 volumiopi4 kernel: [ 1041] 0 1041 1393 461 32 429 0 32768 0 0 sed Feb 11 23:04:18 volumiopi4 kernel: [ 1042] 0 1042 1409 416 33 383 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1044] 106 1044 4360 4025 2454 1571 0 65536 0 0 ntpd Feb 11 23:04:18 volumiopi4 kernel: [ 1065] 0 1065 520 348 0 348 0 24576 0 0 ifplugd Feb 11 23:04:18 volumiopi4 kernel: [ 1072] 0 1072 3391 1970 192 1778 0 53248 0 -1000 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1119] 0 1119 16457 3211 416 2726 69 94208 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1123] 0 1123 16383 1024 406 618 0 86016 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1216] 0 1216 3393 1459 196 1263 0 53248 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 1237] 100 1237 643 296 33 263 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 1273] 0 1273 1115 460 0 460 0 36864 0 0 agetty Feb 11 23:04:18 volumiopi4 kernel: [ 1317] 0 1317 18061 3947 480 3422 45 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1323] 0 1323 18121 3613 482 3088 43 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1354] 0 1354 19290 4326 512 3620 194 118784 0 0 smbd Feb 11 23:04:18 volumiopi4 kernel: [ 1355] 103 1355 1572 831 64 767 0 36864 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1356] 103 1356 1545 419 64 355 0 32768 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1380] 0 1380 18897 2658 501 2157 0 110592 0 0 smbd-notifyd Feb 11 23:04:18 volumiopi4 kernel: [ 1381] 0 1381 18897 1261 501 760 0 110592 0 0 cleanupd Feb 11 23:04:18 volumiopi4 kernel: [ 1386] 0 1386 18061 2676 485 2191 0 106496 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1403] 0 1403 3669 2242 224 2018 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1432] 1000 1432 1400 631 32 599 0 36864 0 0 network_monitor Feb 11 23:04:18 volumiopi4 kernel: [ 1465] 1000 1465 3734 1569 311 1258 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1472] 1000 1472 580 421 32 389 0 28672 0 0 sftp-server Feb 11 23:04:18 volumiopi4 kernel: [ 1485] 1000 1485 31905 8495 931 7564 0 233472 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1495] 1000 1495 33078 11486 2226 9260 0 307200 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1496] 1000 1496 32925 11523 2225 9298 0 303104 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1497] 1000 1497 32989 11517 2202 9315 0 311296 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1562] 0 1562 161161 5997 1554 4443 0 155648 0 0 volumio5-onboar Feb 11 23:04:18 volumiopi4 kernel: [ 1597] 102 1597 119125 23055 5916 17139 0 643072 0 0 mpd Feb 11 23:04:18 volumiopi4 kernel: [ 1664] 104 1664 37337 2884 384 2500 0 143360 0 0 shairport-sync Feb 11 23:04:18 volumiopi4 kernel: [ 1736] 1000 1736 31956 3009 288 2721 0 118784 0 0 upmpdcli Feb 11 23:04:18 volumiopi4 kernel: [ 2043] 0 2043 3669 2240 224 2016 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2068] 1000 2068 3734 1569 311 1258 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2069] 1000 2069 983 846 224 622 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 3529] 1000 3529 515 311 0 311 0 28672 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 3531] 1000 3531 2017 921 64 857 0 45056 0 0 sudo Feb 11 23:04:18 volumiopi4 kernel: [ 3532] 0 3532 515 322 0 322 0 32768 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 5781] 0 5781 24793 23406 12105 11301 0 229376 0 0 apt-get Feb 11 23:04:18 volumiopi4 kernel: [ 5906] 1000 5906 1115 349 0 349 0 32768 0 0 sleep Feb 11 23:04:18 volumiopi4 kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=apt-get,pid=5781,uid=0 Feb 11 23:04:18 volumiopi4 kernel: Out of memory: Killed process 5781 (apt-get) total-vm:99172kB, anon-rss:48420kB, file-rss:45204kB, shmem-rss:0kB, UID:0 pgtables:224kB oom_score_adj:0 Feb 11 23:04:18 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:18.169+01:00 level=ERROR msg="failed reading message" component=volumio/socket error="websocket: close 1006 (abnormal closure): unexpected EOF" Feb 11 23:04:18 volumiopi4 kernel: systemd-journal invoked oom-killer: gfp_mask=0xcc0(GFP_KERNEL), order=0, oom_score_adj=-250 Feb 11 23:04:18 volumiopi4 kernel: CPU: 3 UID: 0 PID: 463 Comm: systemd-journal Tainted: G C 6.12.47-v7l+ #1904 Feb 11 23:04:18 volumiopi4 kernel: Tainted: [C]=CRAP Feb 11 23:04:18 volumiopi4 kernel: Hardware name: BCM2711 Feb 11 23:04:18 volumiopi4 kernel: Call trace: Feb 11 23:04:18 volumiopi4 kernel: unwind_backtrace from show_stack+0x18/0x1c Feb 11 23:04:18 volumiopi4 kernel: show_stack from dump_stack_lvl+0x5c/0x80 Feb 11 23:04:18 volumiopi4 kernel: dump_stack_lvl from dump_header+0x50/0x180 Feb 11 23:04:18 volumiopi4 kernel: dump_header from oom_kill_process+0x230/0x2a0 Feb 11 23:04:18 volumiopi4 kernel: oom_kill_process from out_of_memory+0x104/0x34c Feb 11 23:04:18 volumiopi4 kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Feb 11 23:04:18 volumiopi4 kernel: __alloc_pages_noprof from get_free_pages_noprof+0x1c/0x2c Feb 11 23:04:18 volumiopi4 kernel: get_free_pages_noprof from proc_pid_cmdline_read+0x1ac/0x374 Feb 11 23:04:18 volumiopi4 kernel: proc_pid_cmdline_read from vfs_read+0xb0/0x310 Feb 11 23:04:18 volumiopi4 kernel: vfs_read from ksys_read+0x68/0xec Feb 11 23:04:18 volumiopi4 kernel: ksys_read from __sys_trace_return+0x0/0x10 Feb 11 23:04:18 volumiopi4 kernel: Exception stack(0xf09e1fa8 to 0xf09e1ff0) Feb 11 23:04:18 volumiopi4 kernel: 1fa0: 00001004 00e99300 0000001e 00e99300 00001004 00000000 Feb 11 23:04:18 volumiopi4 kernel: 1fc0: 00001004 00e99300 0000001e 00000003 003ffffe 00000001 be8e64d0 ffffffff Feb 11 23:04:18 volumiopi4 kernel: 1fe0: b6ca4b80 be8e64a0 00000000 b6bcb72c Feb 11 23:04:18 volumiopi4 kernel: Mem-Info: Feb 11 23:04:18 volumiopi4 kernel: active_anon:353 inactive_anon:26359 isolated_anon:0 active_file:52460 inactive_file:168833 isolated_file:0 unevictable:4092 dirty:9164 writeback:0 slab_reclaimable:17130 slab_unreclaimable:6271 mapped:39244 shmem:2034 pagetables:1315 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:684230 free_pcp:5 free_cma:125960 Feb 11 23:04:18 volumiopi4 kernel: Node 0 active_anon:1412kB inactive_anon:105436kB active_file:209840kB inactive_file:675332kB unevictable:16368kB isolated(anon):0kB isolated(file):0kB mapped:156976kB dirty:36656kB writeback:0kB shmem:8136kB writeback_tmp:0kB kernel_stack:2440kB pagetables:5260kB sec_pagetables:0kB all_unreclaimable? no Feb 11 23:04:18 volumiopi4 kernel: DMA free:536072kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:15088kB inactive_file:388kB unevictable:0kB writepending:756kB present:786432kB managed:662964kB mlocked:0kB bounce:0kB free_pcp:20kB local_pcp:0kB free_cma:503840kB Feb 11 23:04:18 volumiopi4 kernel: lowmem_reserve[]: 0 0 3136 3136 Feb 11 23:04:18 volumiopi4 kernel: DMA: 393*4kB (UE) 425*8kB (UEC) 240*16kB (UEC) 166*32kB (UEC) 96*64kB (UMEC) 64*128kB (UEC) 17*256kB (UEC) 12*512kB (UMEC) 4*1024kB (EC) 1*2048kB (C) 120*4096kB (C) = 536620kB Feb 11 23:04:18 volumiopi4 kernel: 224911 total pagecache pages Feb 11 23:04:18 volumiopi4 kernel: 0 pages in swap cache Feb 11 23:04:18 volumiopi4 kernel: Free swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: Total swap = 0kB Feb 11 23:04:18 volumiopi4 kernel: 999424 pages RAM Feb 11 23:04:18 volumiopi4 kernel: 802816 pages HighMem/MovableOnly Feb 11 23:04:18 volumiopi4 kernel: 30867 pages reserved Feb 11 23:04:18 volumiopi4 kernel: 131072 pages cma reserved Feb 11 23:04:18 volumiopi4 kernel: Tasks state (memory values in pages): Feb 11 23:04:18 volumiopi4 kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Feb 11 23:04:18 volumiopi4 kernel: [ 463] 0 463 7671 2147 192 1443 512 65536 0 -250 systemd-journal Feb 11 23:04:18 volumiopi4 kernel: [ 485] 0 485 5991 1186 256 930 0 53248 0 -1000 systemd-udevd Feb 11 23:04:18 volumiopi4 kernel: [ 699] 0 699 1959 1130 765 365 0 40960 0 0 haveged Feb 11 23:04:18 volumiopi4 kernel: [ 702] 108 702 1731 773 64 709 0 40960 0 0 rpcbind Feb 11 23:04:18 volumiopi4 kernel: [ 756] 0 756 2740 1302 64 1238 0 45056 0 0 bluetoothd Feb 11 23:04:18 volumiopi4 kernel: [ 757] 101 757 1916 958 128 830 0 40960 0 -900 dbus-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 769] 0 769 7706 1652 160 1492 0 61440 0 0 systemd-logind Feb 11 23:04:18 volumiopi4 kernel: [ 770] 65534 770 1250 595 32 563 0 32768 0 0 thd Feb 11 23:04:18 volumiopi4 kernel: [ 774] 1000 774 11915 2596 316 2280 0 81920 0 0 volumio-remote- Feb 11 23:04:18 volumiopi4 kernel: [ 778] 0 778 32098 10047 1336 8711 0 258048 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 898] 100 898 639 431 64 367 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 899] 0 899 643 499 64 435 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 902] 100 902 560 278 32 246 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 903] 100 903 560 279 32 247 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 936] 0 936 3275 1336 96 1240 0 49152 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 940] 0 940 10966 1669 96 1573 0 69632 0 0 bluealsa Feb 11 23:04:18 volumiopi4 kernel: [ 1037] 0 1037 1409 646 32 614 0 36864 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1040] 0 1040 930 582 32 550 0 28672 0 0 ip Feb 11 23:04:18 volumiopi4 kernel: [ 1041] 0 1041 1393 461 32 429 0 32768 0 0 sed Feb 11 23:04:18 volumiopi4 kernel: [ 1042] 0 1042 1409 416 33 383 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 1044] 106 1044 4360 4025 2454 1571 0 65536 0 0 ntpd Feb 11 23:04:18 volumiopi4 kernel: [ 1065] 0 1065 520 348 0 348 0 24576 0 0 ifplugd Feb 11 23:04:18 volumiopi4 kernel: [ 1072] 0 1072 3391 1970 192 1778 0 53248 0 -1000 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1119] 0 1119 16457 3211 416 2726 69 94208 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1123] 0 1123 16383 1024 406 618 0 86016 0 0 nmbd Feb 11 23:04:18 volumiopi4 kernel: [ 1216] 0 1216 3393 1459 196 1263 0 53248 0 0 wpa_supplicant Feb 11 23:04:18 volumiopi4 kernel: [ 1237] 100 1237 643 296 33 263 0 28672 0 0 dhcpcd Feb 11 23:04:18 volumiopi4 kernel: [ 1273] 0 1273 1115 460 0 460 0 36864 0 0 agetty Feb 11 23:04:18 volumiopi4 kernel: [ 1317] 0 1317 18061 3947 480 3422 45 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1323] 0 1323 18121 3613 482 3088 43 110592 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1354] 0 1354 19290 4326 512 3620 194 118784 0 0 smbd Feb 11 23:04:18 volumiopi4 kernel: [ 1355] 103 1355 1572 831 64 767 0 36864 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1356] 103 1356 1545 419 64 355 0 32768 0 0 avahi-daemon Feb 11 23:04:18 volumiopi4 kernel: [ 1380] 0 1380 18897 2658 501 2157 0 110592 0 0 smbd-notifyd Feb 11 23:04:18 volumiopi4 kernel: [ 1381] 0 1381 18897 1261 501 760 0 110592 0 0 cleanupd Feb 11 23:04:18 volumiopi4 kernel: [ 1386] 0 1386 18061 2676 485 2191 0 106496 0 0 winbindd Feb 11 23:04:18 volumiopi4 kernel: [ 1403] 0 1403 3669 2242 224 2018 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1432] 1000 1432 1400 631 32 599 0 36864 0 0 network_monitor Feb 11 23:04:18 volumiopi4 kernel: [ 1465] 1000 1465 3734 1569 311 1258 0 57344 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 1472] 1000 1472 580 421 32 389 0 28672 0 0 sftp-server Feb 11 23:04:18 volumiopi4 kernel: [ 1485] 1000 1485 31905 8495 931 7564 0 233472 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1495] 1000 1495 33078 11486 2226 9260 0 307200 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1496] 1000 1496 32925 11523 2225 9298 0 303104 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1497] 1000 1497 32989 11517 2202 9315 0 311296 0 0 node Feb 11 23:04:18 volumiopi4 kernel: [ 1562] 0 1562 161161 5997 1554 4443 0 155648 0 0 volumio5-onboar Feb 11 23:04:18 volumiopi4 kernel: [ 1597] 102 1597 119125 23055 5916 17139 0 643072 0 0 mpd Feb 11 23:04:18 volumiopi4 kernel: [ 1664] 104 1664 37337 2884 384 2500 0 143360 0 0 shairport-sync Feb 11 23:04:18 volumiopi4 kernel: [ 1736] 1000 1736 31956 3009 288 2721 0 118784 0 0 upmpdcli Feb 11 23:04:18 volumiopi4 kernel: [ 2043] 0 2043 3669 2240 224 2016 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2068] 1000 2068 3734 1569 311 1258 0 53248 0 0 sshd Feb 11 23:04:18 volumiopi4 kernel: [ 2069] 1000 2069 983 846 224 622 0 32768 0 0 bash Feb 11 23:04:18 volumiopi4 kernel: [ 3529] 1000 3529 515 311 0 311 0 28672 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 3531] 1000 3531 2017 921 64 857 0 45056 0 0 sudo Feb 11 23:04:18 volumiopi4 kernel: [ 3532] 0 3532 515 322 0 322 0 32768 0 0 sh Feb 11 23:04:18 volumiopi4 kernel: [ 5906] 1000 5906 1115 349 0 349 0 32768 0 0 sleep Feb 11 23:04:18 volumiopi4 kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=mpd,pid=1597,uid=102 Feb 11 23:04:18 volumiopi4 kernel: Out of memory: Killed process 1597 (mpd) total-vm:476500kB, anon-rss:23664kB, file-rss:68556kB, shmem-rss:0kB, UID:102 pgtables:628kB oom_score_adj:0 Feb 11 23:04:18 volumiopi4 kernel: bcm2835-audio bcm2835-audio: failed to close VCHI service connection (status=-11) Feb 11 23:04:18 volumiopi4 systemd[1]: user@1000.service: Main process exited, code=killed, status=9/KILL Feb 11 23:04:18 volumiopi4 volumio-remote-updater[774]: [2026-02-11 23:04:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 11 23:04:18 volumiopi4 volumio-remote-updater[774]: [2026-02-11 23:04:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 11 23:04:18 volumiopi4 sudo[3531]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:18 volumiopi4 systemd[1]: user@1000.service: Failed with result 'signal'. Feb 11 23:04:18 volumiopi4 systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL Feb 11 23:04:18 volumiopi4 systemd[1]: mpd.service: Failed with result 'signal'. Feb 11 23:04:18 volumiopi4 systemd[1]: mpd.service: Consumed 16.546s CPU time. Feb 11 23:04:18 volumiopi4 systemd[1]: volumio.service: Main process exited, code=killed, status=9/KILL Feb 11 23:04:18 volumiopi4 systemd[1]: volumio.service: Failed with result 'signal'. Feb 11 23:04:18 volumiopi4 systemd[1]: volumio.service: Consumed 17min 6.408s CPU time. Feb 11 23:04:18 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:18.301+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 11 23:04:18 volumiopi4 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 23:04:18 volumiopi4 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 23:04:18 volumiopi4 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 11 23:04:18 volumiopi4 systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 11 23:04:18 volumiopi4 systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 11 23:04:18 volumiopi4 systemd[1]: volumio.service: Consumed 17min 6.408s CPU time. Feb 11 23:04:18 volumiopi4 systemd[1]: Started volumio.service - Volumio Backend Module. Feb 11 23:04:18 volumiopi4 systemd[1]: dynamicswap.service: Deactivated successfully. Feb 11 23:04:19 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:19.309+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 11 23:04:20 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:20.310+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 11 23:04:21 volumiopi4 volumio5-onboarding[1562]: time=2026-02-11T23:04:21.310+01:00 level=WARN msg="reconnection attempt failed" component=volumio/socket error="dial tcp 127.0.0.1:3000: connect: connection refused" Feb 11 23:04:22 volumiopi4 volumio[6368]: info: ------------------------------------------- Feb 11 23:04:22 volumiopi4 volumio[6368]: info: ----- Volumio3 ---- Feb 11 23:04:22 volumiopi4 volumio[6368]: info: ------------------------------------------- Feb 11 23:04:22 volumiopi4 volumio[6368]: info: ----- System startup ---- Feb 11 23:04:22 volumiopi4 volumio[6368]: info: ------------------------------------------- Feb 11 23:04:23 volumiopi4 volumio-remote-updater[774]: [2026-02-11 23:04:23] [connect] Successful connection Feb 11 23:04:23 volumiopi4 volumio[6368]: info: MYVOLUMIO Environment detected Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Plugin folders cleanup Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning into folder /volumio/app/plugins/ Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category audio_interface Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category miscellanea Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category music_service Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category plugins.json Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category system_controller Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category user_interface Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning into folder /data/plugins/ Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category system_controller Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Scanning category user_interface Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Plugin folders cleanup completed Feb 11 23:04:23 volumiopi4 volumio[6368]: info: ------------------------------------------- Feb 11 23:04:23 volumiopi4 volumio[6368]: info: ----- Core plugins startup ---- Feb 11 23:04:23 volumiopi4 volumio[6368]: info: ------------------------------------------- Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Loading plugins from folder /volumio/app/plugins/ Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Adding plugin upnp to MyMusic Plugins Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Loading plugins from folder /data/plugins/ Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Loading plugin "system"... Feb 11 23:04:23 volumiopi4 volumio[6368]: info: Loading plugin "appearance"... Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Loading plugin "network"... Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Refreshing Cached IP Addresses Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Loading plugin "services"... Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Loading plugin "volumio5onboarding"... Feb 11 23:04:24 volumiopi4 sudo[6407]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 11 23:04:24 volumiopi4 sudo[6397]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 23:04:24 volumiopi4 sudo[6399]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 23:04:24 volumiopi4 sudo[6399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:24 volumiopi4 sudo[6407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:24 volumiopi4 sudo[6397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:24 volumiopi4 sudo[6399]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:24 volumiopi4 sudo[6397]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Loading plugin "alsa_controller"... Feb 11 23:04:24 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Loading plugin "wizard"... Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Loading plugin "networkfs"... Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Starting Udev Watcher for removable devices Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Ignoring mount for partition: boot Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Ignoring mount for partition: volumio Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Ignoring mount for partition: volumio_data Feb 11 23:04:24 volumiopi4 volumio[6368]: info: Mounting Device SanDisk Feb 11 23:04:25 volumiopi4 sudo[6436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/SanDisk -o noatime Feb 11 23:04:25 volumiopi4 sudo[6436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:25 volumiopi4 sudo[6436]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:25 volumiopi4 volumio[6368]: mount: /media/SanDisk: /dev/sda1 already mounted on /media/SanDisk. Feb 11 23:04:25 volumiopi4 volumio[6368]: dmesg(1) may have more information after failed mount system call. Feb 11 23:04:25 volumiopi4 volumio[6368]: error: Failed to mount SanDisk: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/SanDisk" -o noatime Feb 11 23:04:25 volumiopi4 volumio[6368]: mount: /media/SanDisk: /dev/sda1 already mounted on /media/SanDisk. Feb 11 23:04:25 volumiopi4 volumio[6368]: dmesg(1) may have more information after failed mount system call. Feb 11 23:04:25 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 23:04:25 volumiopi4 volumio[6368]: info: Loading plugin "volumio_command_line_client"... Feb 11 23:04:25 volumiopi4 volumio[6368]: info: Loading plugin "upnp"... Feb 11 23:04:25 volumiopi4 volumio[6368]: info: [1770847465102] Starting Upmpd Daemon Feb 11 23:04:25 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 23:04:25 volumiopi4 volumio[6368]: info: Loading plugin "my_music"... Feb 11 23:04:25 volumiopi4 volumio[6368]: info: Loading plugin "mpd"... Feb 11 23:04:25 volumiopi4 volumio[6368]: info: Loading plugin "upnp_browser"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Starting UPNP Browser Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "alarm-clock"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "airplay_emulation"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Starting Shairport Sync Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "last_100"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "webradio"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "i2s_dacs"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: I2S DAC not set, start Auto-detection Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "volumiodiscovery"... Feb 11 23:04:26 volumiopi4 volumio[6368]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 23:04:26 volumiopi4 volumio[6368]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 23:04:26 volumiopi4 volumio[6368]: *** WARNING *** For more information see Feb 11 23:04:26 volumiopi4 volumio[6368]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 23:04:26 volumiopi4 volumio[6368]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 23:04:26 volumiopi4 volumio[6368]: *** WARNING *** For more information see Feb 11 23:04:26 volumiopi4 node[6368]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 11 23:04:26 volumiopi4 node[6368]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 23:04:26 volumiopi4 node[6368]: *** WARNING *** For more information see Feb 11 23:04:26 volumiopi4 node[6368]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 11 23:04:26 volumiopi4 node[6368]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 11 23:04:26 volumiopi4 node[6368]: *** WARNING *** For more information see Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Discovery: Started advertising with name: VolumioPi4 Feb 11 23:04:26 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "autostart"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Applying required configuration parameters for plugin autostart Feb 11 23:04:26 volumiopi4 volumio[6368]: info: AutoStart - onVolumioStart - read config.json Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Plugin peppy_screensaver is not enabled Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "outputs"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "albumart"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Plugin example_plugin is not enabled Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "inputs"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "updater_comm"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Plugin mpdemulation is not enabled Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "rest_api"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading plugin "websocket"... Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Starting Socket.io Server version 1.7.4 Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Plugin backup_restore is not enabled Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Plugin Systeminfo is not enabled Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Plugin touch_display is not enabled Feb 11 23:04:26 volumiopi4 volumio[6368]: info: Loading i18n strings for locale de Feb 11 23:04:26 volumiopi4 volumio[6368]: Updating browse sources language Feb 11 23:04:26 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 23:04:27 volumiopi4 volumio[6440]: Forking 3 albumart workers Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::initPlayerControls Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: Express server listening on port 3000 Feb 11 23:04:27 volumiopi4 volumio[6368]: [Metrics] WebUI: 5s 829.71ms Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::resetVolumioState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::getcurrentVolume Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Volumio Network Manager: Network status updated: 2 Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to 192.168.1.111 from 192.168.1.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 2 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: VolumeController:: Volume=50 Mute =false Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::updateTrackBlock Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrackBlock Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioRetrievevolume Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Reloading queue from file Feb 11 23:04:27 volumiopi4 volumio-remote-updater[774]: [2026-02-11 23:04:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1770847463 101 Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::setRepeat null single undefined Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::setRandom null Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Setting Device type: Raspberry PI Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to 192.168.1.111 from 192.168.1.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 11 23:04:27 volumiopi4 volumio[6368]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: VolumeController:: Volume=50 Mute =false Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Completed loading Core Plugins Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Preparing to generate the ALSA configuration file Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 11 23:04:27 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Asound.conf file unchanged, so no further update is needed Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Output device has changed, restarting MPD Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Output device has changed, restarting Shairport Sync Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:27 volumiopi4 sudo[6498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 23:04:27 volumiopi4 sudo[6498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:27 volumiopi4 sudo[6498]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: ___________ START PLUGINS ___________ Feb 11 23:04:27 volumiopi4 sudo[6500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 23:04:27 volumiopi4 sudo[6500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:27 volumiopi4 volumio[6368]: info: ControllerMpd::onStart: Initializing MPD Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Creating MPD Configuration file Feb 11 23:04:27 volumiopi4 systemd[1]: mpd.socket: Deactivated successfully. Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 23:04:27 volumiopi4 volumio[6368]: info: [1770847467727] CoreMusicLibrary::Adding element Medienserver Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 23:04:27 volumiopi4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 23:04:27 volumiopi4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 23:04:27 volumiopi4 sudo[6508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Feb 11 23:04:27 volumiopi4 sudo[6508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:27 volumiopi4 volumio[6368]: info: UPNP Browser: Client initialized successfully Feb 11 23:04:27 volumiopi4 sudo[6510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 11 23:04:27 volumiopi4 sudo[6510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:27 volumiopi4 sudo[6510]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:27 volumiopi4 sudo[6512]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 11 23:04:27 volumiopi4 sudo[6512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:27 volumiopi4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:27 volumiopi4 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:27 volumiopi4 sudo[6508]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:27 volumiopi4 systemd[1]: mpd.service: Deactivated successfully. Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 11 23:04:27 volumiopi4 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 23:04:27 volumiopi4 volumio[6368]: info: [1770847467873] CoreMusicLibrary::Adding element Last_100 Feb 11 23:04:27 volumiopi4 systemd[1]: mpd.socket: Deactivated successfully. Feb 11 23:04:27 volumiopi4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 11 23:04:27 volumiopi4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 23:04:27 volumiopi4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 11 23:04:27 volumiopi4 volumio[6368]: info: [1770847467887] CoreMusicLibrary::Adding element Webradio Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 23:04:27 volumiopi4 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Initializing BBC Radios Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:04:27 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:27 volumiopi4 volumio[6368]: info: AutoStart - onStart Feb 11 23:04:27 volumiopi4 volumio[6368]: info: Volumio Calling Home Feb 11 23:04:28 volumiopi4 sudo[6527]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 11 23:04:28 volumiopi4 sudo[6527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 11 23:04:28 volumiopi4 sudo[6527]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:28 volumiopi4 volumio[6450]: Starting albumart workers Feb 11 23:04:28 volumiopi4 volumio[6452]: Starting albumart workers Feb 11 23:04:28 volumiopi4 sudo[6407]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:28 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: adding cef44066-8d02-44b3-873f-092d2e523b13 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: Found device VolumioPi4 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: this is already registered, cef44066-8d02-44b3-873f-092d2e523b13 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: Found device VolumioPi4 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:28 volumiopi4 volumio[6451]: Starting albumart workers Feb 11 23:04:28 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to 192.168.1.111:3000 from 192.168.1.105 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: MPD Permissions set Feb 11 23:04:28 volumiopi4 volumio[6368]: info: MPD Permissions set Feb 11 23:04:28 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Volumio called home Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Received Get System Info Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: Getting this device information Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: adding 7473cdcb-996b-4d4f-903d-861eda0f00fc Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: Found device VolumioPi5 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: Connecting to remote: 192.168.1.105 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Starting Shairport Sync Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Starting Shairport Sync Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Starting Shairport Sync Feb 11 23:04:28 volumiopi4 sudo[6572]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 23:04:28 volumiopi4 sudo[6572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:28 volumiopi4 sudo[6574]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 23:04:28 volumiopi4 sudo[6576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 11 23:04:28 volumiopi4 sudo[6576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:28 volumiopi4 sudo[6574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:28 volumiopi4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 23:04:28 volumiopi4 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 23:04:28 volumiopi4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 23:04:28 volumiopi4 systemd[1]: shairport-sync.service: Consumed 3.979s CPU time. Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:28 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:28 volumiopi4 volumio[6368]: info: Discovery: Connected to remote: 192.168.1.105 Feb 11 23:04:28 volumiopi4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 23:04:28 volumiopi4 sudo[6572]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:29 volumiopi4 volumio[6368]: info: Shairport-Sync Started Feb 11 23:04:29 volumiopi4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 11 23:04:29 volumiopi4 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 11 23:04:29 volumiopi4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 23:04:29 volumiopi4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 11 23:04:29 volumiopi4 sudo[6576]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:29 volumiopi4 volumio[6368]: Error adding Membership: Error: addMembership EINVAL Feb 11 23:04:29 volumiopi4 volumio[6368]: info: Shairport-Sync Started Feb 11 23:04:29 volumiopi4 sudo[6574]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:29 volumiopi4 volumio[6368]: info: Shairport-Sync Started Feb 11 23:04:30 volumiopi4 mpd[6544]: 2026-02-11T23:04:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 11 23:04:30 volumiopi4 systemd[1]: Started mpd.service - Music Player Daemon. Feb 11 23:04:30 volumiopi4 sudo[6512]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:30 volumiopi4 sudo[6500]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:30 volumiopi4 volumio[6368]: info: Completed starting Core Plugins Feb 11 23:04:30 volumiopi4 volumio[6368]: info: ------------------------------------------- Feb 11 23:04:30 volumiopi4 volumio[6368]: info: ----- MyVolumio plugins startup ---- Feb 11 23:04:30 volumiopi4 volumio[6368]: info: ------------------------------------------- Feb 11 23:04:30 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 11 23:04:30 volumiopi4 volumio[6368]: error: MPD error: The expression evaluated to a falsy value: Feb 11 23:04:30 volumiopi4 volumio[6368]: assert.ok(self.idling) Feb 11 23:04:30 volumiopi4 volumio[6368]: error: The expression evaluated to a falsy value: Feb 11 23:04:30 volumiopi4 volumio[6368]: assert.ok(self.idling) Feb 11 23:04:30 volumiopi4 volumio[6368]: error: updateQueue error: null Feb 11 23:04:30 volumiopi4 volumio[6368]: info: MPD running with PID6544 Feb 11 23:04:30 volumiopi4 volumio[6368]: ,establishing connection Feb 11 23:04:30 volumiopi4 volumio[6368]: error: updateQueue error: null Feb 11 23:04:32 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to 192.168.1.111 from 192.168.1.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Edg/144.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 23:04:32 volumiopi4 volumio[6368]: info: Received Get System Info Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 23:04:32 volumiopi4 volumio[6368]: info: Discovery: Getting this device information Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:32 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:32 volumiopi4 volumio[6368]: info: Listing playlists Feb 11 23:04:35 volumiopi4 sudo[6623]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 23:04:35 volumiopi4 sudo[6623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:35 volumiopi4 sudo[6625]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 23:04:35 volumiopi4 sudo[6625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:35 volumiopi4 sudo[6623]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:35 volumiopi4 sudo[6625]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:35 volumiopi4 sudo[6629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 11 23:04:35 volumiopi4 sudo[6629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:35 volumiopi4 sudo[6629]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:35 volumiopi4 volumio[6368]: info: Upmpdcli Daemon Started Feb 11 23:04:36 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:36 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:36 volumiopi4 ntpd[1044]: PROTO: 139.144.71.56 unlink local addr 192.168.1.111 -> Feb 11 23:04:39 volumiopi4 volumio[6368]: Cannot compose Albumart path Feb 11 23:04:39 volumiopi4 volumio[6368]: Cannot compose Albumart path Feb 11 23:04:39 volumiopi4 volumio[6368]: Cannot compose Albumart path Feb 11 23:04:39 volumiopi4 volumio[6368]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 23:04:39 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 23:04:39 volumiopi4 volumio[6368]: Disk list : [] Feb 11 23:04:39 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 23:04:39 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 23:04:39 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 11 23:04:39 volumiopi4 volumio[6368]: info: Received Get System Version Feb 11 23:04:39 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 11 23:04:40 volumiopi4 volumio[6368]: info: Adding plugin bluetooth to MyMusic Plugins Feb 11 23:04:40 volumiopi4 volumio[6368]: info: Adding plugin multiroom to MyMusic Plugins Feb 11 23:04:40 volumiopi4 volumio[6368]: info: Adding plugin metavolumio to MyMusic Plugins Feb 11 23:04:40 volumiopi4 volumio[6368]: info: Adding plugin cd_controller to MyMusic Plugins Feb 11 23:04:40 volumiopi4 volumio[6368]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 11 23:04:40 volumiopi4 volumio[6368]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 11 23:04:40 volumiopi4 volumio[6368]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 11 23:04:40 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 11 23:04:41 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 11 23:04:41 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 11 23:04:41 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:41 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:41 volumiopi4 volumio[6368]: info: Starting MyVolumio Remote Streaming Endpoints Feb 11 23:04:41 volumiopi4 volumio[6368]: info: MyVolumio login type: Token Feb 11 23:04:41 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 11 23:04:41 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 11 23:04:41 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 11 23:04:41 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 11 23:04:41 volumiopi4 volumio[6368]: info: Streaming services startup Feb 11 23:04:41 volumiopi4 volumio[6368]: info: Starting Streaming Daemon Feb 11 23:04:41 volumiopi4 sudo[6652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 23:04:41 volumiopi4 sudo[6652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:41 volumiopi4 volumio[6368]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 11 23:04:41 volumiopi4 sudo[6652]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:41 volumiopi4 volumio[6368]: error: Cannot start Volumio Streaming Daemon Feb 11 23:04:41 volumiopi4 volumio[6368]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 23:04:41 volumiopi4 volumio[6368]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 23:04:42 volumiopi4 volumio[6368]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:42 volumiopi4 volumio[6368]: info: AutoStart - Plugin is starting Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetQueue Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::getQueue Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CorePlayQueue::getQueue Feb 11 23:04:42 volumiopi4 volumio[6368]: info: AutoStart - start playing Feb 11 23:04:42 volumiopi4 volumio[6368]: info: AutoStart - start playing with no specific position Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPlay Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::play index 0 Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::stop Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::play index undefined Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CoreStateMachine::startPlaybackTimer Feb 11 23:04:42 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:42 volumiopi4 volumio[6368]: info: [1770847482995] ControllerWebradio::clearAddPlayTrack Feb 11 23:04:42 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand stop Feb 11 23:04:42 volumiopi4 volumio[6368]: info: sendMpdCommand stop took 1 milliseconds Feb 11 23:04:42 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand clear Feb 11 23:04:42 volumiopi4 volumio[6368]: info: Feb 11 23:04:42 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: sendMpdCommand clear took 2 milliseconds Feb 11 23:04:43 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand load "http://streams.80s80s.de/web/mp3-192/tunein/play.m3u" Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Feb 11 23:04:43 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Feb 11 23:04:43 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:43 volumiopi4 volumio[6368]: error: updateQueue error: null Feb 11 23:04:43 volumiopi4 volumio[6368]: info: ------------------------------ 6ms Feb 11 23:04:43 volumiopi4 volumio[6368]: info: sendMpdCommand load "http://streams.80s80s.de/web/mp3-192/tunein/play.m3u" took 78 milliseconds Feb 11 23:04:43 volumiopi4 volumio[6368]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 11 23:04:43 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand play Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Feb 11 23:04:43 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: ------------------------------ 80ms Feb 11 23:04:43 volumiopi4 volumio[6368]: info: ------------------------------ 79ms Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Feb 11 23:04:43 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Feb 11 23:04:43 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:43 volumiopi4 volumio[6368]: info: sendMpdCommand play took 5 milliseconds Feb 11 23:04:43 volumiopi4 volumio[6368]: info: ------------------------------ 5ms Feb 11 23:04:43 volumiopi4 volumio[6368]: info: ------------------------------ 2ms Feb 11 23:04:43 volumiopi4 volumio[6368]: info: ------------------------------ 2ms Feb 11 23:04:43 volumiopi4 volumio[6368]: info: MyVolumio token set successfully Feb 11 23:04:43 volumiopi4 volumio[6368]: info: MYVOLUMIO: Adding device Feb 11 23:04:43 volumiopi4 volumio[6368]: info: MYVOLUMIO: Evaluating Server Feb 11 23:04:43 volumiopi4 volumio[6368]: info: MyVolumio status changed Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Streaming services startup Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Starting Streaming Daemon Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Removing browser output: myVolumio user plan is not superstar Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Removing audio output: Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Stoppping Tunnel 1 Feb 11 23:04:43 volumiopi4 sudo[6683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 11 23:04:43 volumiopi4 sudo[6683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:43 volumiopi4 sudo[6685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 11 23:04:43 volumiopi4 sudo[6685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:43 volumiopi4 sudo[6683]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:43 volumiopi4 volumio[6368]: error: Cannot start Volumio Streaming Daemon Feb 11 23:04:43 volumiopi4 volumio[6368]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 11 23:04:43 volumiopi4 volumio[6368]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 11 23:04:43 volumiopi4 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 23:04:43 volumiopi4 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 23:04:43 volumiopi4 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 23:04:43 volumiopi4 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 23:04:43 volumiopi4 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 23:04:43 volumiopi4 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 11 23:04:43 volumiopi4 sudo[6685]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Remote SSH Stopped Feb 11 23:04:43 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 23:04:43 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 23:04:43 volumiopi4 volumio[6368]: info: Setting Geolocation for MyVolumio to eu2 Feb 11 23:04:43 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:43 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:43 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 16 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 16 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 14 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 6 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 3 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 2 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","artist":null,"album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus stop Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","artist":null,"album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","artist":null,"album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 47ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 60ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 60ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 39 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 37 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","artist":null,"album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 112ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 106ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 106ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Feb 11 23:04:44 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 87ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 87 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 11ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 9 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 10ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand status took 8 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"80s80s Digital Web","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:04:44 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 134ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 56ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: ------------------------------ 55ms Feb 11 23:04:44 volumiopi4 volumio[6368]: info: Updating MyVolumio device info Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:44 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:45 volumiopi4 volumio[6368]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 11 23:04:47 volumiopi4 volumio[6368]: info: Enabling plugin touch_display Feb 11 23:04:47 volumiopi4 volumio[6368]: info: Loading plugin "touch_display"... Feb 11 23:04:47 volumiopi4 volumio[6368]: info: PLUGIN START: touch_display Feb 11 23:04:47 volumiopi4 volumio[6368]: info: Loading i18n strings for locale de Feb 11 23:04:48 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:04:48 volumiopi4 sudo[6706]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 11 23:04:48 volumiopi4 sudo[6706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:48 volumiopi4 sudo[6706]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:48 volumiopi4 sudo[6709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 11 23:04:48 volumiopi4 volumio[6368]: info: MYVOLUMIO: Adding device Feb 11 23:04:48 volumiopi4 volumio[6368]: info: MYVOLUMIO: Evaluating Server Feb 11 23:04:48 volumiopi4 sudo[6709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:48 volumiopi4 volumio[6368]: info: touch_display: No backlight interface detected. Feb 11 23:04:48 volumiopi4 sudo[6714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 11 23:04:48 volumiopi4 sudo[6714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:48 volumiopi4 sudo[6711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 11 23:04:48 volumiopi4 sudo[6711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:48 volumiopi4 systemd[1]: Stopping getty@tty1.service - Getty on tty1... Feb 11 23:04:48 volumiopi4 systemd[1]: getty@tty1.service: Deactivated successfully. Feb 11 23:04:48 volumiopi4 systemd[1]: Stopped getty@tty1.service - Getty on tty1. Feb 11 23:04:48 volumiopi4 sudo[6719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 11 23:04:48 volumiopi4 sudo[6719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:48 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 11 23:04:48 volumiopi4 sudo[6719]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:48 volumiopi4 systemd[1]: Reloading. Feb 11 23:04:48 volumiopi4 volumio[6368]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 11 23:04:48 volumiopi4 volumio[6368]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 11 23:04:48 volumiopi4 sudo[6722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 11 23:04:48 volumiopi4 sudo[6722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:48 volumiopi4 sudo[6722]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:48 volumiopi4 volumio[6368]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 11 23:04:48 volumiopi4 volumio[6368]: info: Setting Geolocation for MyVolumio to eu8 Feb 11 23:04:48 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:48 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:48 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:48 volumiopi4 volumio[6368]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Feb 11 23:04:49 volumiopi4 sudo[6714]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:49 volumiopi4 volumio[6368]: info: touch_display: systemctl daemon-reload succeeded. Feb 11 23:04:49 volumiopi4 sudo[6760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 11 23:04:49 volumiopi4 sudo[6760]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:04:49 volumiopi4 systemd[1]: Reloading. Feb 11 23:04:49 volumiopi4 volumio[6368]: info: Updating MyVolumio device info Feb 11 23:04:49 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:49 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:49 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:49 volumiopi4 volumio[6368]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 11 23:04:49 volumiopi4 volumio[6368]: info: touch_display: About to detect HDMI ports... Feb 11 23:04:49 volumiopi4 volumio[6368]: info: touch_display: X server not ready, cannot detect HDMI ports yet. Feb 11 23:04:49 volumiopi4 volumio[6368]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Feb 11 23:04:50 volumiopi4 sudo[6711]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:50 volumiopi4 volumio[6368]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 11 23:04:50 volumiopi4 sudo[6709]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:50 volumiopi4 volumio[6368]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 11 23:04:50 volumiopi4 sudo[6760]: pam_unix(sudo:session): session closed for user root Feb 11 23:04:50 volumiopi4 volumio[6368]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Feb 11 23:04:50 volumiopi4 volumio[6368]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Feb 11 23:04:50 volumiopi4 volumio[6368]: info: Error: Error Feb 11 23:04:56 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 23:04:56 volumiopi4 volumio[6368]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 11 23:04:56 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetState Feb 11 23:04:58 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:04:58 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:04:58 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 23:04:58 volumiopi4 volumio-remote-updater[774]: Test mode disabled Feb 11 23:04:58 volumiopi4 volumio-remote-updater[774]: Alpha mode disabled Feb 11 23:04:58 volumiopi4 volumio-remote-updater[774]: Alpha legacy test mode disabled Feb 11 23:04:58 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 11 23:04:58 volumiopi4 volumio[6368]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 11 23:04:58 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioGetBrowseSources Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 23:05:01 volumiopi4 volumio[6368]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 11 23:05:01 volumiopi4 volumio[6368]: info: Completed starting MyVolumio Plugin Feb 11 23:05:01 volumiopi4 volumio[6368]: [Metrics] CommandRouter: 39s 45.61ms Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumiosetStartupVolume Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 11 23:05:01 volumiopi4 volumio[6368]: info: VolumeController:: Setting startup Volume 50 Feb 11 23:05:01 volumiopi4 volumio[6368]: info: VolumeController::SetAlsaVolume50 Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::Close All Modals sent Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::Close All Modals sent Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:05:01 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:05:02 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 11 23:05:02 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 11 23:05:02 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 11 23:05:05 volumiopi4 wpa_supplicant[1216]: wlan0: WPA: Group rekeying completed with e2:08:55:05:3c:80 [GTK=CCMP] Feb 11 23:05:07 volumiopi4 volumio[6368]: info: CALLMETHOD: user_interface touch_display saveVirtualKeyboardConf [object Object] Feb 11 23:05:07 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveVirtualKeyboardConf Feb 11 23:05:08 volumiopi4 volumio[6368]: info: BOOT COMPLETED Feb 11 23:05:12 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 23:05:12 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Feb 11 23:05:22 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Feb 11 23:05:22 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Feb 11 23:05:22 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Feb 11 23:05:22 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Feb 11 23:05:22 volumiopi4 volumio[6368]: ---------------------------- MPD announces system playlist update Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Ignoring MPD Status Update Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Feb 11 23:05:22 volumiopi4 volumio[6368]: ---------------------------- MPD announces state update: player Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ControllerMpd::getState Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand status Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ------------------------------ 7ms Feb 11 23:05:22 volumiopi4 volumio[6368]: info: sendMpdCommand status took 5 milliseconds Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ------------------------------ 5ms Feb 11 23:05:22 volumiopi4 volumio[6368]: info: sendMpdCommand status took 4 milliseconds Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ------------------------------ 6ms Feb 11 23:05:22 volumiopi4 volumio[6368]: info: sendMpdCommand status took 4 milliseconds Feb 11 23:05:22 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 11 23:05:22 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseState Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39109,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Corey Hart - Sunglasses at Night","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39109,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Corey Hart - Sunglasses at Night","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ------------------------------ 49ms Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ------------------------------ 46ms Feb 11 23:05:22 volumiopi4 volumio[6368]: info: sendMpdCommand playlistinfo took 39 milliseconds Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: ControllerMpd::parseTrackInfo Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ControllerMpd::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::servicePushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CorePlayQueue::getTrack 0 Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39109,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Corey Hart - Sunglasses at Night","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=698pspso%230%23p46nn9n1s20n3p4rpqo4r4oqn6106139%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1770847483","trackType":""} Feb 11 23:05:22 volumiopi4 volumio[6368]: verbose: CURRENT POSITION 0 Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState stateService play Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::syncState currentStatus play Feb 11 23:05:22 volumiopi4 volumio[6368]: info: Received an update from plugin. extracting info from payload Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreStateMachine::pushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: CoreCommandRouter::volumioPushState Feb 11 23:05:22 volumiopi4 volumio[6368]: info: ------------------------------ 64ms Feb 11 23:05:22 volumiopi4 sudo[6835]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 11 23:05:22 volumiopi4 sudo[6835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:05:22 volumiopi4 sudo[6835]: pam_unix(sudo:session): session closed for user root Feb 11 23:05:22 volumiopi4 sudo[6837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 11 23:05:22 volumiopi4 volumio[6368]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 11 23:05:22 volumiopi4 volumio[6368]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 23:05:22 volumiopi4 sudo[6837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:05:22 volumiopi4 volumio[6368]: TypeError: Cannot read properties of undefined (reading 'split') Feb 11 23:05:22 volumiopi4 volumio[6368]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Feb 11 23:05:22 volumiopi4 volumio[6368]: at ChildProcess.exithandler (node:child_process:413:7) Feb 11 23:05:22 volumiopi4 volumio[6368]: at ChildProcess.emit (node:events:514:28) Feb 11 23:05:22 volumiopi4 volumio[6368]: at maybeClose (node:internal/child_process:1105:16) Feb 11 23:05:22 volumiopi4 volumio[6368]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Feb 11 23:05:22 volumiopi4 volumio[6368]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 23:05:22 volumiopi4 sudo[6839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 11 23:05:22 volumiopi4 sudo[6839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:05:22 volumiopi4 sudo[6842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 11 23:05:22 volumiopi4 sudo[6842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 23:05:22 volumiopi4 sudo[6842]: pam_unix(sudo:session): session closed for user root Feb 11 23:05:22 volumiopi4 systemd[1]: Reloading. Feb 11 23:05:22 volumiopi4 sudo[6877]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 23:04' Feb 11 23:05:22 volumiopi4 sudo[6877]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"