May 26 20:53:07 volumio volumio[7774]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---list is Random,black-balbuze99,white-balbuze99,large-white-rb--balbuze99,large-black-gr-balbuze99,large-black-br-balbuze99,large-black-br-r-balbuze99,large-black-gr-r-balbuze99,large-black-gr-rs-balbuze99,large-white-gr-r-balbuze99 May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-custom May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-Gelo5 May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x100+99-99bars May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x480+20-marshall May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x480+230-230-bars-freq May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-Gelo5 May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-marshall May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x480+55-marshall May 26 20:53:07 volumio volumio[7774]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] May 26 20:53:17 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 May 26 20:53:17 volumio volumio[7774]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum -----------------------spectrumwhite-balbuze99 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum -----------------------$basefolder/data/INTERNAL/PeppySpectrum/Templates May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum -----------------------screensize1280x400+99-99bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum -----------------------screenwidth1280 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum -----------------------screenheight400 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum -----------------------spectrumsize99 May 26 20:53:17 volumio volumio[7774]: error: PeppySpectrum ---Error writing config null May 26 20:53:17 volumio sudo[10452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service May 26 20:53:17 volumio sudo[10452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:17 volumio systemd[1]: peppyspectrum.service: Found left-over process 10341 (python3) in control group while starting unit. Ignoring. May 26 20:53:17 volumio systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies. May 26 20:53:17 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. May 26 20:53:17 volumio sudo[10452]: pam_unix(sudo:session): session closed for user root May 26 20:53:17 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:53:17 volumio volumio[7774]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 20:53:17 volumio volumio[7774]: info: CoreCommandRouter::Close All Modals sent May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---list is Random,black-balbuze99,white-balbuze99,large-white-rb--balbuze99,large-black-gr-balbuze99,large-black-br-balbuze99,large-black-br-r-balbuze99,large-black-gr-r-balbuze99,large-black-gr-rs-balbuze99,large-white-gr-r-balbuze99 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-custom May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-Gelo5 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x100+99-99bars May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x480+20-marshall May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x480+230-230-bars-freq May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-Gelo5 May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-marshall May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x480+55-marshall May 26 20:53:17 volumio volumio[7774]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] May 26 20:53:22 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 May 26 20:53:22 volumio volumio[7774]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum -----------------------spectrumlarge-black-br-balbuze99 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum -----------------------$basefolder/data/INTERNAL/PeppySpectrum/Templates May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum -----------------------screensize1280x400+99-99bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum -----------------------screenwidth1280 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum -----------------------screenheight400 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum -----------------------spectrumsize99 May 26 20:53:22 volumio volumio[7774]: error: PeppySpectrum ---Error writing config null May 26 20:53:22 volumio sudo[10478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service May 26 20:53:22 volumio sudo[10478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:22 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... May 26 20:53:22 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. May 26 20:53:22 volumio systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. May 26 20:53:22 volumio systemd[1]: peppyspectrum.service: Consumed 2.235s CPU time. May 26 20:53:22 volumio volumio[7774]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 20:53:22 volumio volumio[7774]: info: CoreCommandRouter::Close All Modals sent May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---list is Random,black-balbuze99,white-balbuze99,large-white-rb--balbuze99,large-black-gr-balbuze99,large-black-br-balbuze99,large-black-br-r-balbuze99,large-black-gr-r-balbuze99,large-black-gr-rs-balbuze99,large-white-gr-r-balbuze99 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-custom May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-Gelo5 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x100+99-99bars May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x480+20-marshall May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x480+230-230-bars-freq May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-Gelo5 May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-marshall May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x480+55-marshall May 26 20:53:22 volumio volumio[7774]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 26 20:53:22 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. May 26 20:53:22 volumio sudo[10478]: pam_unix(sudo:session): session closed for user root May 26 20:53:22 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:53:41 volumio volumio[7774]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] May 26 20:53:41 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum May 26 20:53:44 volumio volumio[7774]: --2026-05-26 20:53:41-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/1920x1080+55-Gelo5.zip May 26 20:53:44 volumio volumio[7774]: Resolving github.com (github.com)... 140.82.121.3 May 26 20:53:44 volumio volumio[7774]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. May 26 20:53:44 volumio volumio[7774]: HTTP request sent, awaiting response... 302 Found May 26 20:53:44 volumio volumio[7774]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/Zipped-folders/1920x1080%2B55-Gelo5.zip [following] May 26 20:53:44 volumio volumio[7774]: --2026-05-26 20:53:42-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/Zipped-folders/1920x1080%2B55-Gelo5.zip May 26 20:53:44 volumio volumio[7774]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.110.133, 185.199.111.133, 185.199.108.133, ... May 26 20:53:44 volumio volumio[7774]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.110.133|:443... connected. May 26 20:53:44 volumio volumio[7774]: HTTP request sent, awaiting response... 200 OK May 26 20:53:44 volumio volumio[7774]: Length: 9080129 (8.7M) [application/zip] May 26 20:53:44 volumio volumio[7774]: Saving to: ‘/tmp/1920x1080+55-Gelo5.zip’ May 26 20:53:44 volumio volumio[7774]: 0K .......... .......... .......... .......... .......... 0% 887K 10s May 26 20:53:44 volumio volumio[7774]: 50K .......... .......... .......... .......... .......... 1% 2.74M 7s May 26 20:53:44 volumio volumio[7774]: 100K .......... .......... .......... .......... .......... 1% 1.61M 6s May 26 20:53:44 volumio volumio[7774]: 150K .......... .......... .......... .......... .......... 2% 5.00M 5s May 26 20:53:44 volumio volumio[7774]: 200K .......... .......... .......... .......... .......... 2% 8.64M 4s May 26 20:53:44 volumio volumio[7774]: 250K .......... .......... .......... .......... .......... 3% 2.24M 4s May 26 20:53:44 volumio volumio[7774]: 300K .......... .......... .......... .......... .......... 3% 5.81M 4s May 26 20:53:44 volumio volumio[7774]: 350K .......... .......... .......... .......... .......... 4% 5.90M 3s May 26 20:53:44 volumio volumio[7774]: 400K .......... .......... .......... .......... .......... 5% 16.7M 3s May 26 20:53:44 volumio volumio[7774]: 450K .......... .......... .......... .......... .......... 5% 12.2M 3s May 26 20:53:44 volumio volumio[7774]: 500K .......... .......... .......... .......... .......... 6% 11.5M 3s May 26 20:53:44 volumio volumio[7774]: 550K .......... .......... .......... .......... .......... 6% 6.63M 2s May 26 20:53:44 volumio volumio[7774]: 600K .......... .......... .......... .......... .......... 7% 5.61M 2s May 26 20:53:44 volumio volumio[7774]: 650K .......... .......... .......... .......... .......... 7% 7.16M 2s May 26 20:53:44 volumio volumio[7774]: 700K .......... .......... .......... .......... .......... 8% 9.05M 2s May 26 20:53:44 volumio volumio[7774]: 750K .......... .......... .......... .......... .......... 9% 24.8M 2s May 26 20:53:44 volumio volumio[7774]: 800K .......... .......... .......... .......... .......... 9% 9.05M 2s May 26 20:53:44 volumio volumio[7774]: 850K .......... .......... .......... .......... .......... 10% 23.2M 2s May 26 20:53:44 volumio volumio[7774]: 900K .......... .......... .......... .......... .......... 10% 11.7M 2s May 26 20:53:44 volumio volumio[7774]: 950K .......... .......... .......... .......... .......... 11% 16.8M 2s May 26 20:53:44 volumio volumio[7774]: 1000K .......... .......... .......... .......... .......... 11% 13.6M 2s May 26 20:53:44 volumio volumio[7774]: 1050K .......... .......... .......... .......... .......... 12% 23.4M 2s May 26 20:53:44 volumio volumio[7774]: 1100K .......... .......... .......... .......... .......... 12% 23.1M 1s May 26 20:53:44 volumio volumio[7774]: 1150K .......... .......... .......... .......... .......... 13% 16.3M 1s May 26 20:53:44 volumio volumio[7774]: 1200K .......... .......... .......... .......... .......... 14% 10.3M 1s May 26 20:53:44 volumio volumio[7774]: 1250K .......... .......... .......... .......... .......... 14% 34.3M 1s May 26 20:53:44 volumio volumio[7774]: 1300K .......... .......... .......... .......... .......... 15% 12.7M 1s May 26 20:53:44 volumio volumio[7774]: 1350K .......... .......... .......... .......... .......... 15% 16.5M 1s May 26 20:53:44 volumio volumio[7774]: 1400K .......... .......... .......... .......... .......... 16% 9.72M 1s May 26 20:53:44 volumio volumio[7774]: 1450K .......... .......... .......... .......... .......... 16% 20.2M 1s May 26 20:53:44 volumio volumio[7774]: 1500K .......... .......... .......... .......... .......... 17% 16.8M 1s May 26 20:53:44 volumio volumio[7774]: 1550K .......... .......... .......... .......... .......... 18% 14.6M 1s May 26 20:53:44 volumio volumio[7774]: 1600K .......... .......... .......... .......... .......... 18% 23.5M 1s May 26 20:53:44 volumio volumio[7774]: 1650K .......... .......... .......... .......... .......... 19% 25.3M 1s May 26 20:53:44 volumio volumio[7774]: 1700K .......... .......... .......... .......... .......... 19% 26.2M 1s May 26 20:53:44 volumio volumio[7774]: 1750K .......... .......... .......... .......... .......... 20% 16.4M 1s May 26 20:53:44 volumio volumio[7774]: 1800K .......... .......... .......... .......... .......... 20% 15.9M 1s May 26 20:53:44 volumio volumio[7774]: 1850K .......... .......... .......... .......... .......... 21% 26.0M 1s May 26 20:53:44 volumio volumio[7774]: 1900K .......... .......... .......... .......... .......... 21% 18.2M 1s May 26 20:53:44 volumio volumio[7774]: 1950K .......... .......... .......... .......... .......... 22% 13.7M 1s May 26 20:53:44 volumio volumio[7774]: 2000K .......... .......... .......... .......... .......... 23% 29.1M 1s May 26 20:53:44 volumio volumio[7774]: 2050K .......... .......... .......... .......... .......... 23% 24.9M 1s May 26 20:53:44 volumio volumio[7774]: 2100K .......... .......... .......... .......... .......... 24% 20.1M 1s May 26 20:53:44 volumio volumio[7774]: 2150K .......... .......... .......... .......... .......... 24% 17.5M 1s May 26 20:53:44 volumio volumio[7774]: 2200K .......... .......... .......... .......... .......... 25% 21.0M 1s May 26 20:53:44 volumio volumio[7774]: 2250K .......... .......... .......... .......... .......... 25% 16.0M 1s May 26 20:53:44 volumio volumio[7774]: 2300K .......... .......... .......... .......... .......... 26% 30.0M 1s May 26 20:53:44 volumio volumio[7774]: 2350K .......... .......... .......... .......... .......... 27% 15.8M 1s May 26 20:53:44 volumio volumio[7774]: 2400K .......... .......... .......... .......... .......... 27% 27.2M 1s May 26 20:53:44 volumio volumio[7774]: 2450K .......... .......... .......... .......... .......... 28% 14.9M 1s May 26 20:53:44 volumio volumio[7774]: 2500K .......... .......... .......... .......... .......... 28% 17.4M 1s May 26 20:53:44 volumio volumio[7774]: 2550K .......... .......... .......... .......... .......... 29% 15.7M 1s May 26 20:53:44 volumio volumio[7774]: 2600K .......... .......... .......... .......... .......... 29% 14.1M 1s May 26 20:53:44 volumio volumio[7774]: 2650K .......... .......... .......... .......... .......... 30% 36.8M 1s May 26 20:53:44 volumio volumio[7774]: 2700K .......... .......... .......... .......... .......... 31% 13.3M 1s May 26 20:53:44 volumio volumio[7774]: 2750K .......... .......... .......... .......... .......... 31% 27.7M 1s May 26 20:53:44 volumio volumio[7774]: 2800K .......... .......... .......... .......... .......... 32% 31.1M 1s May 26 20:53:44 volumio volumio[7774]: 2850K .......... .......... .......... .......... .......... 32% 18.9M 1s May 26 20:53:44 volumio volumio[7774]: 2900K .......... .......... .......... .......... .......... 33% 23.9M 1s May 26 20:53:44 volumio volumio[7774]: 2950K .......... .......... .......... .......... .......... 33% 18.6M 1s May 26 20:53:44 volumio volumio[7774]: 3000K .......... .......... .......... .......... .......... 34% 28.6M 1s May 26 20:53:44 volumio volumio[7774]: 3050K .......... .......... .......... .......... .......... 34% 29.3M 1s May 26 20:53:44 volumio volumio[7774]: 3100K .......... .......... .......... .......... .......... 35% 17.2M 1s May 26 20:53:44 volumio volumio[7774]: 3150K .......... .......... .......... .......... .......... 36% 20.6M 1s May 26 20:53:44 volumio volumio[7774]: 3200K .......... .......... .......... .......... .......... 36% 12.9M 1s May 26 20:53:44 volumio volumio[7774]: 3250K .......... .......... .......... .......... .......... 37% 25.2M 1s May 26 20:53:44 volumio volumio[7774]: 3300K .......... .......... .......... .......... .......... 37% 13.8M 1s May 26 20:53:44 volumio volumio[7774]: 3350K .......... .......... .......... .......... .......... 38% 23.3M 1s May 26 20:53:44 volumio volumio[7774]: 3400K .......... .......... .......... .......... .......... 38% 17.6M 1s May 26 20:53:44 volumio volumio[7774]: 3450K .......... .......... .......... .......... .......... 39% 36.2M 1s May 26 20:53:44 volumio volumio[7774]: 3500K .......... .......... .......... .......... .......... 40% 11.2M 1s May 26 20:53:44 volumio volumio[7774]: 3550K .......... .......... .......... .......... .......... 40% 20.5M 1s May 26 20:53:44 volumio volumio[7774]: 3600K .......... .......... .......... .......... .......... 41% 17.5M 1s May 26 20:53:44 volumio volumio[7774]: 3650K .......... .......... .......... .......... .......... 41% 18.0M 0s May 26 20:53:44 volumio volumio[7774]: 3700K .......... .......... .......... .......... .......... 42% 27.0M 0s May 26 20:53:44 volumio volumio[7774]: 3750K .......... .......... .......... .......... .......... 42% 28.9M 0s May 26 20:53:44 volumio volumio[7774]: 3800K .......... .......... .......... .......... .......... 43% 9.78M 0s May 26 20:53:44 volumio volumio[7774]: 3850K .......... .......... .......... .......... .......... 43% 42.1M 0s May 26 20:53:44 volumio volumio[7774]: 3900K .......... .......... .......... .......... .......... 44% 18.0M 0s May 26 20:53:44 volumio volumio[7774]: 3950K .......... .......... .......... .......... .......... 45% 11.6M 0s May 26 20:53:44 volumio volumio[7774]: 4000K .......... .......... .......... .......... .......... 45% 12.6M 0s May 26 20:53:44 volumio volumio[7774]: 4050K .......... .......... .......... .......... .......... 46% 15.7M 0s May 26 20:53:44 volumio volumio[7774]: 4100K .......... .......... .......... .......... .......... 46% 15.6M 0s May 26 20:53:44 volumio volumio[7774]: 4150K .......... .......... .......... .......... .......... 47% 20.4M 0s May 26 20:53:44 volumio volumio[7774]: 4200K .......... .......... .......... .......... .......... 47% 17.7M 0s May 26 20:53:44 volumio volumio[7774]: 4250K .......... .......... .......... .......... .......... 48% 24.6M 0s May 26 20:53:44 volumio volumio[7774]: 4300K .......... .......... .......... .......... .......... 49% 13.5M 0s May 26 20:53:44 volumio volumio[7774]: 4350K .......... .......... .......... .......... .......... 49% 15.7M 0s May 26 20:53:44 volumio volumio[7774]: 4400K .......... .......... .......... .......... .......... 50% 17.1M 0s May 26 20:53:44 volumio volumio[7774]: 4450K .......... .......... .......... .......... .......... 50% 26.9M 0s May 26 20:53:44 volumio volumio[7774]: 4500K .......... .......... .......... .......... .......... 51% 21.4M 0s May 26 20:53:44 volumio volumio[7774]: 4550K .......... .......... .......... .......... .......... 51% 24.7M 0s May 26 20:53:44 volumio volumio[7774]: 4600K .......... .......... .......... .......... .......... 52% 25.1M 0s May 26 20:53:44 volumio volumio[7774]: 4650K .......... .......... .......... .......... .......... 53% 18.1M 0s May 26 20:53:44 volumio volumio[7774]: 4700K .......... .......... .......... .......... .......... 53% 23.1M 0s May 26 20:53:44 volumio volumio[7774]: 4750K .......... .......... .......... .......... .......... 54% 18.2M 0s May 26 20:53:44 volumio volumio[7774]: 4800K .......... .......... .......... .......... .......... 54% 14.8M 0s May 26 20:53:44 volumio volumio[7774]: 4850K .......... .......... .......... .......... .......... 55% 14.2M 0s May 26 20:53:44 volumio volumio[7774]: 4900K .......... .......... .......... .......... .......... 55% 7.48M 0s May 26 20:53:44 volumio volumio[7774]: 4950K .......... .......... .......... .......... .......... 56% 65.0M 0s May 26 20:53:44 volumio volumio[7774]: 5000K .......... .......... .......... .......... .......... 56% 72.6M 0s May 26 20:53:44 volumio volumio[7774]: 5050K .......... .......... .......... .......... .......... 57% 13.5M 0s May 26 20:53:44 volumio volumio[7774]: 5100K .......... .......... .......... .......... .......... 58% 10.9M 0s May 26 20:53:44 volumio volumio[7774]: 5150K .......... .......... .......... .......... .......... 58% 14.1M 0s May 26 20:53:44 volumio volumio[7774]: 5200K .......... .......... .......... .......... .......... 59% 28.7M 0s May 26 20:53:44 volumio volumio[7774]: 5250K .......... .......... .......... .......... .......... 59% 22.6M 0s May 26 20:53:44 volumio volumio[7774]: 5300K .......... .......... .......... .......... .......... 60% 12.1M 0s May 26 20:53:44 volumio volumio[7774]: 5350K .......... .......... .......... .......... .......... 60% 33.9M 0s May 26 20:53:44 volumio volumio[7774]: 5400K .......... .......... .......... .......... .......... 61% 9.13M 0s May 26 20:53:44 volumio volumio[7774]: 5450K .......... .......... .......... .......... .......... 62% 15.1M 0s May 26 20:53:44 volumio volumio[7774]: 5500K .......... .......... .......... .......... .......... 62% 19.3M 0s May 26 20:53:44 volumio volumio[7774]: 5550K .......... .......... .......... .......... .......... 63% 7.39M 0s May 26 20:53:44 volumio volumio[7774]: 5600K .......... .......... .......... .......... .......... 63% 41.5M 0s May 26 20:53:44 volumio volumio[7774]: 5650K .......... .......... .......... .......... .......... 64% 22.9M 0s May 26 20:53:44 volumio volumio[7774]: 5700K .......... .......... .......... .......... .......... 64% 19.4M 0s May 26 20:53:44 volumio volumio[7774]: 5750K .......... .......... .......... .......... .......... 65% 28.1M 0s May 26 20:53:44 volumio volumio[7774]: 5800K .......... .......... .......... .......... .......... 65% 11.7M 0s May 26 20:53:44 volumio volumio[7774]: 5850K .......... .......... .......... .......... .......... 66% 19.1M 0s May 26 20:53:44 volumio volumio[7774]: 5900K .......... .......... .......... .......... .......... 67% 28.2M 0s May 26 20:53:44 volumio volumio[7774]: 5950K .......... .......... .......... .......... .......... 67% 18.9M 0s May 26 20:53:44 volumio volumio[7774]: 6000K .......... .......... .......... .......... .......... 68% 27.5M 0s May 26 20:53:44 volumio volumio[7774]: 6050K .......... .......... .......... .......... .......... 68% 20.5M 0s May 26 20:53:44 volumio volumio[7774]: 6100K .......... .......... .......... .......... .......... 69% 30.1M 0s May 26 20:53:44 volumio volumio[7774]: 6150K .......... .......... .......... .......... .......... 69% 25.6M 0s May 26 20:53:44 volumio volumio[7774]: 6200K .......... .......... .......... .......... .......... 70% 25.0M 0s May 26 20:53:44 volumio volumio[7774]: 6250K .......... .......... .......... .......... .......... 71% 7.03M 0s May 26 20:53:44 volumio volumio[7774]: 6300K .......... .......... .......... .......... .......... 71% 25.7M 0s May 26 20:53:44 volumio volumio[7774]: 6350K .......... .......... .......... .......... .......... 72% 15.4M 0s May 26 20:53:44 volumio volumio[7774]: 6400K .......... .......... .......... .......... .......... 72% 18.5M 0s May 26 20:53:44 volumio volumio[7774]: 6450K .......... .......... .......... .......... .......... 73% 22.1M 0s May 26 20:53:44 volumio volumio[7774]: 6500K .......... .......... .......... .......... .......... 73% 26.0M 0s May 26 20:53:44 volumio volumio[7774]: 6550K .......... .......... .......... .......... .......... 74% 20.3M 0s May 26 20:53:44 volumio volumio[7774]: 6600K .......... .......... .......... .......... .......... 74% 25.7M 0s May 26 20:53:44 volumio volumio[7774]: 6650K .......... .......... .......... .......... .......... 75% 16.3M 0s May 26 20:53:44 volumio volumio[7774]: 6700K .......... .......... .......... .......... .......... 76% 16.7M 0s May 26 20:53:44 volumio volumio[7774]: 6750K .......... .......... .......... .......... .......... 76% 20.6M 0s May 26 20:53:44 volumio volumio[7774]: 6800K .......... .......... .......... .......... .......... 77% 20.0M 0s May 26 20:53:44 volumio volumio[7774]: 6850K .......... .......... .......... .......... .......... 77% 22.7M 0s May 26 20:53:44 volumio volumio[7774]: 6900K .......... .......... .......... .......... .......... 78% 17.1M 0s May 26 20:53:44 volumio volumio[7774]: 6950K .......... .......... .......... .......... .......... 78% 19.6M 0s May 26 20:53:44 volumio volumio[7774]: 7000K .......... .......... .......... .......... .......... 79% 20.6M 0s May 26 20:53:44 volumio volumio[7774]: 7050K .......... .......... .......... .......... .......... 80% 12.4M 0s May 26 20:53:44 volumio volumio[7774]: 7100K .......... .......... .......... .......... .......... 80% 21.6M 0s May 26 20:53:44 volumio volumio[7774]: 7150K .......... .......... .......... .......... .......... 81% 14.6M 0s May 26 20:53:44 volumio volumio[7774]: 7200K .......... .......... .......... .......... .......... 81% 21.9M 0s May 26 20:53:44 volumio volumio[7774]: 7250K .......... .......... .......... .......... .......... 82% 13.7M 0s May 26 20:53:44 volumio volumio[7774]: 7300K .......... .......... .......... .......... .......... 82% 29.3M 0s May 26 20:53:44 volumio volumio[7774]: 7350K .......... .......... .......... .......... .......... 83% 27.1M 0s May 26 20:53:44 volumio volumio[7774]: 7400K .......... .......... .......... .......... .......... 84% 15.4M 0s May 26 20:53:44 volumio volumio[7774]: 7450K .......... .......... .......... .......... .......... 84% 40.9M 0s May 26 20:53:44 volumio volumio[7774]: 7500K .......... .......... .......... .......... .......... 85% 20.2M 0s May 26 20:53:44 volumio volumio[7774]: 7550K .......... .......... .......... .......... .......... 85% 14.2M 0s May 26 20:53:44 volumio volumio[7774]: 7600K .......... .......... .......... .......... .......... 86% 16.4M 0s May 26 20:53:44 volumio volumio[7774]: 7650K .......... .......... .......... .......... .......... 86% 14.3M 0s May 26 20:53:44 volumio volumio[7774]: 7700K .......... .......... .......... .......... .......... 87% 13.4M 0s May 26 20:53:44 volumio volumio[7774]: 7750K .......... .......... .......... .......... .......... 87% 15.2M 0s May 26 20:53:44 volumio volumio[7774]: 7800K .......... .......... .......... .......... .......... 88% 7.80M 0s May 26 20:53:44 volumio volumio[7774]: 7850K .......... .......... .......... .......... .......... 89% 44.5M 0s May 26 20:53:44 volumio volumio[7774]: 7900K .......... .......... .......... .......... .......... 89% 37.7M 0s May 26 20:53:44 volumio volumio[7774]: 7950K .......... .......... .......... .......... .......... 90% 11.8M 0s May 26 20:53:44 volumio volumio[7774]: 8000K .......... .......... .......... .......... .......... 90% 11.4M 0s May 26 20:53:44 volumio volumio[7774]: 8050K .......... .......... .......... .......... .......... 91% 27.5M 0s May 26 20:53:44 volumio volumio[7774]: 8100K .......... .......... .......... .......... .......... 91% 11.8M 0s May 26 20:53:44 volumio volumio[7774]: 8150K .......... .......... .......... .......... .......... 92% 28.5M 0s May 26 20:53:44 volumio volumio[7774]: 8200K .......... .......... .......... .......... .......... 93% 28.6M 0s May 26 20:53:44 volumio volumio[7774]: 8250K .......... .......... .......... .......... .......... 93% 20.4M 0s May 26 20:53:44 volumio volumio[7774]: 8300K .......... .......... .......... .......... .......... 94% 29.6M 0s May 26 20:53:44 volumio volumio[7774]: 8350K .......... .......... .......... .......... .......... 94% 8.94M 0s May 26 20:53:44 volumio volumio[7774]: 8400K .......... .......... .......... .......... .......... 95% 37.3M 0s May 26 20:53:44 volumio volumio[7774]: 8450K .......... .......... .......... .......... .......... 95% 21.3M 0s May 26 20:53:44 volumio volumio[7774]: 8500K .......... .......... .......... .......... .......... 96% 28.5M 0s May 26 20:53:44 volumio volumio[7774]: 8550K .......... .......... .......... .......... .......... 96% 26.8M 0s May 26 20:53:44 volumio volumio[7774]: 8600K .......... .......... .......... .......... .......... 97% 21.6M 0s May 26 20:53:44 volumio volumio[7774]: 8650K .......... .......... .......... .......... .......... 98% 28.3M 0s May 26 20:53:44 volumio volumio[7774]: 8700K .......... .......... .......... .......... .......... 98% 18.2M 0s May 26 20:53:44 volumio volumio[7774]: 8750K .......... .......... .......... .......... .......... 99% 19.4M 0s May 26 20:53:44 volumio volumio[7774]: 8800K .......... .......... .......... .......... .......... 99% 9.53M 0s May 26 20:53:44 volumio volumio[7774]: 8850K .......... ....... 100% 186M=0.6s May 26 20:53:44 volumio volumio[7774]: 2026-05-26 20:53:44 (13.5 MB/s) - ‘/tmp/1920x1080+55-Gelo5.zip’ saved [9080129/9080129] May 26 20:53:44 volumio sudo[10523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /data/INTERNAL/PeppySpectrum/Templates/ May 26 20:53:44 volumio sudo[10523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:44 volumio sudo[10523]: pam_unix(sudo:session): session closed for user root May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---message miniunzip -o /tmp/1920x1080+55-Gelo5.zip -d /data/INTERNAL/PeppySpectrum/Templates/ May 26 20:53:44 volumio volumio[7774]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 20:53:44 volumio volumio[7774]: info: CoreCommandRouter::Close All Modals sent May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---list is Random,black-balbuze99,white-balbuze99,large-white-rb--balbuze99,large-black-gr-balbuze99,large-black-br-balbuze99,large-black-br-r-balbuze99,large-black-gr-r-balbuze99,large-black-gr-rs-balbuze99,large-white-gr-r-balbuze99 May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-custom May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-Gelo5 May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x100+99-99bars May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x480+20-marshall May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x480+230-230-bars-freq May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-Gelo5 May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-marshall May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x480+55-marshall May 26 20:53:44 volumio volumio[7774]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 26 20:53:49 volumio volumio[7774]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] May 26 20:53:49 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy May 26 20:53:49 volumio volumio[7774]: aaaaaaaaaaa 1920,1080+55-Gelo5 May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---19201080+55-Gelo5 May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1920108055 May 26 20:53:49 volumio volumio[7774]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') May 26 20:53:49 volumio volumio[7774]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 20:53:49 volumio volumio[7774]: info: CoreCommandRouter::Close All Modals sent May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---list is Random,G5_01Marshall,G5_02Kenwood,G5_03M6100 Gold,G5_04Naim,G5_05McIntosh,G5_06Advance,G5_07Marantz,G5_07Luxman,G5_08Sansui May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-custom May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-Gelo5 May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x100+99-99bars May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x480+20-marshall May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x480+230-230-bars-freq May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-Gelo5 May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-marshall May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x480+55-marshall May 26 20:53:49 volumio volumio[7774]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 26 20:53:51 volumio volumio[7774]: info: Preparing to generate the ALSA configuration file May 26 20:53:51 volumio volumio[7774]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 26 20:53:51 volumio volumio[7774]: info: Reading ALSA contributions from plugins. May 26 20:53:51 volumio volumio[7774]: info: Asound.conf file written May 26 20:53:51 volumio sudo[10533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 26 20:53:51 volumio sudo[10533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio sudo[10533]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio volumio[7774]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 26 20:53:51 volumio volumio[7774]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 26 20:53:51 volumio volumio[7774]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 26 20:53:51 volumio volumio[7774]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 May 26 20:53:51 volumio volumio[7774]: info: Output device has changed, restarting MPD May 26 20:53:51 volumio volumio[7774]: info: Output device has changed, restarting Shairport Sync May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 20:53:51 volumio sudo[10540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 20:53:51 volumio sudo[10540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio sudo[10540]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio sudo[10541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 20:53:51 volumio sudo[10541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 20:53:51 volumio volumio[7774]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: Relinquishing Volumio State May 26 20:53:51 volumio volumio[7774]: verbose: UNSET VOLATILE: Service: tidalconnect May 26 20:53:51 volumio volumio[7774]: info: unSetVolatile called May 26 20:53:51 volumio volumio[7774]: info: Relinquishing Volumio State to another service May 26 20:53:51 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 26 20:53:51 volumio vtcs[10204]: [2026-05-26 20:53:51.200] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE May 26 20:53:51 volumio vtcs[10204]: [2026-05-26 20:53:51.201] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer May 26 20:53:51 volumio vtcs[10204]: [2026-05-26 20:53:51.201] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected May 26 20:53:51 volumio vtcs[10204]: [close:85] Entering May 26 20:53:51 volumio volumio[7774]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 26 20:53:51 volumio volumio[7774]: info: MPD Permissions set May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio sudo[10553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 20:53:51 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 20:53:51 volumio sudo[10553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 20:53:51 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 20:53:51 volumio vtcs[10204]: [feedThread:276] Exiting May 26 20:53:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 20:53:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 20:53:51 volumio vtcs[10204]: [close:100] Exiting May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: Starting Shairport Sync May 26 20:53:51 volumio sudo[10551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 20:53:51 volumio sudo[10551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: Preparing to generate the ALSA configuration file May 26 20:53:51 volumio sudo[10564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 20:53:51 volumio sudo[10564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 26 20:53:51 volumio volumio[7774]: info: Reading ALSA contributions from plugins. May 26 20:53:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 20:53:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 20:53:51 volumio volumio[7774]: info: Asound.conf file unchanged, so no further update is needed May 26 20:53:51 volumio volumio[7774]: info: Output device has changed, restarting MPD May 26 20:53:51 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... May 26 20:53:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 20:53:51 volumio sudo[10568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 20:53:51 volumio sudo[10568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio sudo[10568]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 20:53:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 20:53:51 volumio systemd[1]: shairport-sync.service: Consumed 1.630s CPU time. May 26 20:53:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 20:53:51 volumio sudo[10570]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 20:53:51 volumio sudo[10570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio systemd[1]: vtcs.service: Deactivated successfully. May 26 20:53:51 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 26 20:53:51 volumio systemd[1]: vtcs.service: Consumed 1.541s CPU time. May 26 20:53:51 volumio sudo[10564]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio volumio[7774]: info: Output device has changed, restarting Shairport Sync May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 20:53:51 volumio sudo[10566]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 20:53:51 volumio sudo[10566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 20:53:51 volumio sudo[10566]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio volumio[7774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 20:53:51 volumio volumio[7774]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: Relinquishing Volumio State May 26 20:53:51 volumio volumio[7774]: info: MPD Permissions set May 26 20:53:51 volumio volumio[7774]: info: Shairport-Sync Started May 26 20:53:51 volumio sudo[10597]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 20:53:51 volumio sudo[10597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio sudo[10551]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio sudo[10599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 20:53:51 volumio sudo[10599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio sudo[10553]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 20:53:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: Starting Shairport Sync May 26 20:53:51 volumio sudo[10597]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio volumio[7774]: info: Preparing to generate the ALSA configuration file May 26 20:53:51 volumio sudo[10599]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 20:53:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 20:53:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 20:53:51 volumio volumio[7774]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 26 20:53:51 volumio volumio[7774]: info: Reading ALSA contributions from plugins. May 26 20:53:51 volumio sudo[10612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 20:53:51 volumio sudo[10612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: Asound.conf file unchanged, so no further update is needed May 26 20:53:51 volumio volumio[7774]: info: Output device has changed, restarting MPD May 26 20:53:51 volumio sudo[10616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 20:53:51 volumio sudo[10616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 20:53:51 volumio volumio[7774]: info: Output device has changed, restarting Shairport Sync May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio sudo[10616]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 20:53:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 20:53:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 20:53:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 20:53:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 20:53:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 20:53:51 volumio sudo[10612]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio sudo[10619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 20:53:51 volumio sudo[10619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 20:53:51 volumio volumio[7774]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio sudo[10621]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 20:53:51 volumio sudo[10621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 20:53:51 volumio sudo[10621]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 20:53:51 volumio volumio[7774]: info: Relinquishing Volumio State May 26 20:53:51 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 20:53:51 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 20:53:51 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 20:53:51 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 20:53:51 volumio sudo[10644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 20:53:51 volumio sudo[10644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: MPD Permissions set May 26 20:53:51 volumio sudo[10646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 20:53:51 volumio volumio[7774]: info: Shairport-Sync Started May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio sudo[10646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 20:53:51 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 20:53:51 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 20:53:51 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 20:53:51 volumio volumio[7774]: info: Starting Shairport Sync May 26 20:53:51 volumio sudo[10658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 20:53:51 volumio sudo[10658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:51 volumio sudo[10644]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio sudo[10656]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 20:53:51 volumio sudo[10646]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio sudo[10656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 20:53:51 volumio sudo[10656]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 20:53:51 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 20:53:51 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 20:53:51 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 20:53:51 volumio sudo[10658]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio volumio[7774]: info: Shairport-Sync Started May 26 20:53:51 volumio mpd[10663]: 2026-05-26T20:53:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 20:53:51 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 26 20:53:51 volumio sudo[10570]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio sudo[10619]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio sudo[10541]: pam_unix(sudo:session): session closed for user root May 26 20:53:51 volumio volumio[7774]: error: MPD error: The expression evaluated to a falsy value: May 26 20:53:51 volumio volumio[7774]: assert.ok(self.idling) May 26 20:53:51 volumio volumio[7774]: error: The expression evaluated to a falsy value: May 26 20:53:51 volumio volumio[7774]: assert.ok(self.idling) May 26 20:53:51 volumio volumio[7774]: error: MPD error: The expression evaluated to a falsy value: May 26 20:53:51 volumio volumio[7774]: assert.ok(self.idling) May 26 20:53:51 volumio volumio[7774]: error: The expression evaluated to a falsy value: May 26 20:53:51 volumio volumio[7774]: assert.ok(self.idling) May 26 20:53:51 volumio volumio[7774]: error: updateQueue error: null May 26 20:53:54 volumio volumio[7774]: info: TidalConnect service stoped! May 26 20:53:54 volumio volumio[7774]: info: TidalConnect service stoped! May 26 20:53:54 volumio volumio[7774]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 20:53:54 volumio volumio[7774]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 20:53:54 volumio sudo[10704]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 20:53:54 volumio sudo[10704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:54 volumio volumio[7774]: info: TidalConnect service stoped! May 26 20:53:54 volumio volumio[7774]: info: TidalConnect service stoped! May 26 20:53:54 volumio volumio[7774]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 20:53:54 volumio volumio[7774]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 20:53:54 volumio sudo[10716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 20:53:54 volumio sudo[10716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:54 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 26 20:53:54 volumio sudo[10704]: pam_unix(sudo:session): session closed for user root May 26 20:53:54 volumio sudo[10716]: pam_unix(sudo:session): session closed for user root May 26 20:53:54 volumio volumio[7774]: info: Executing endpoint tc_getconfig May 26 20:53:54 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 26 20:53:54 volumio vtcs[10718]: STARTING TidalConnect services, version: 1.6.1 May 26 20:53:54 volumio vtcs[10718]: STARTED TidalConnect services. May 26 20:53:54 volumio volumio[7774]: info: Executing endpoint tc_connect May 26 20:53:54 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 26 20:53:54 volumio volumio[7774]: info: Connecting to TidalConnect May 26 20:53:54 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:53:54 volumio volumio[7774]: info: CorePlayQueue::getTrack 0 May 26 20:53:54 volumio volumio[7774]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 26 20:53:54 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:53:54 volumio volumio[7774]: info: CorePlayQueue::getTrack 0 May 26 20:53:54 volumio volumio[7774]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 26 20:53:54 volumio volumio[7774]: info: TidalConnect service stoped! May 26 20:53:54 volumio volumio[7774]: info: TidalConnect service stoped! May 26 20:53:54 volumio volumio[7774]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 20:53:54 volumio volumio[7774]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 20:53:54 volumio sudo[10744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 20:53:54 volumio sudo[10744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:53:54 volumio sudo[10744]: pam_unix(sudo:session): session closed for user root May 26 20:53:57 volumio volumio[7774]: info: TidalConnect service started! May 26 20:53:57 volumio volumio[7774]: info: TidalConnect service started! May 26 20:53:57 volumio volumio[7774]: info: TidalConnect service started! May 26 20:53:57 volumio vtcs[10718]: [2026-05-26 20:53:57.971] [tisoc] [warning] [SessionManagerImpl.cpp:144] No Session: IDLE May 26 20:53:57 volumio vtcs[10718]: [2026-05-26 20:53:57] [info] asio async_shutdown error: asio.misc:2 (End of file) May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::volumioGetState May 26 20:54:03 volumio volumio[7774]: info: CorePlayQueue::getTrack 0 May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::volumioGetState May 26 20:54:03 volumio volumio[7774]: info: CorePlayQueue::getTrack 0 May 26 20:54:03 volumio volumio[7774]: info: Currently active: tidal May 26 20:54:03 volumio volumio[7774]: info: Stopping currently active service May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::volumioStop May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::stop May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::stPlaybackTimer May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::updateTrackBlock May 26 20:54:03 volumio volumio[7774]: info: CorePlayQueue::getTrackBlock May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:03 volumio volumio[7774]: info: CorePlayQueue::getTrack 0 May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:03 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:03 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::serviceStop May 26 20:54:03 volumio volumio[7774]: info: CorePlayQueue::getTrack 0 May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::serviceStop May 26 20:54:03 volumio volumio[7774]: info: [1779821643326] ControllerTidal::stop May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 20:54:03 volumio volumio[7774]: info: ControllerMpd::stop May 26 20:54:03 volumio volumio[7774]: verbose: ControllerMpd::sendMpdCommand stop May 26 20:54:03 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:03.330+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_STOPPED positionMs=0 volume=100 May 26 20:54:03 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:03.330+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id=tidal://song/317459010 title="Girl You Know It's True" May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status stop May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status stop May 26 20:54:03 volumio volumio[7774]: info: sendMpdCommand stop took 28 milliseconds May 26 20:54:03 volumio volumio[7774]: info: TidalConnect Active May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::volumioGetState May 26 20:54:03 volumio volumio[7774]: info: Currently active: mpd May 26 20:54:03 volumio volumio[7774]: info: Setting Volatile state to tidalconnect May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:03 volumio sudo[10769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 26 20:54:03 volumio sudo[10769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:03 volumio sudo[10772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 26 20:54:03 volumio sudo[10772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:03 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:03 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:03 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:03 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:03 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:03 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:03 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:03.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=65099 volume=100 May 26 20:54:03 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:03.370+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:03 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:03.370+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=65099 volume=100 May 26 20:54:03 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:03.370+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:03 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:03 volumio sudo[10781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:03 volumio sudo[10781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:03 volumio sudo[10783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:03 volumio sudo[10783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:03 volumio sudo[10769]: pam_unix(sudo:session): session closed for user root May 26 20:54:03 volumio sudo[10772]: pam_unix(sudo:session): session closed for user root May 26 20:54:03 volumio sudo[10786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service May 26 20:54:03 volumio volumio[7774]: Job for peppyspectrum.service canceled. May 26 20:54:03 volumio sudo[10786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:03 volumio volumio[7774]: info: touch_display: Setting screensaver timeout to 0 seconds. May 26 20:54:03 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service May 26 20:54:03 volumio volumio[7774]: Job for peppyspectrum.service canceled. May 26 20:54:03 volumio sudo[10790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:03 volumio sudo[10790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:03 volumio systemd[1]: peppyspectrum.service: Deactivated successfully. May 26 20:54:03 volumio systemd[1]: peppyspectrum.service: Consumed 8.634s CPU time. May 26 20:54:03 volumio systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. May 26 20:54:03 volumio sudo[10783]: pam_unix(sudo:session): session closed for user root May 26 20:54:03 volumio sudo[10786]: pam_unix(sudo:session): session closed for user root May 26 20:54:03 volumio sudo[10781]: pam_unix(sudo:session): session closed for user root May 26 20:54:03 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:03 volumio sudo[10790]: pam_unix(sudo:session): session closed for user root May 26 20:54:03 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:03 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:03 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:04 volumio vtcs[10718]: [2026-05-26 20:54:04.200] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:1 May 26 20:54:04 volumio vtcs[10718]: [2026-05-26 20:54:04.491] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 May 26 20:54:04 volumio volumio[7774]: info: Pushing metadata May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:04 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:04 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:04 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.503+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=65099 volume=100 May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.503+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.503+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=3700 volume=100 May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.504+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PAUSED positionMs=3700 volume=100 May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.504+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.504+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:04 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.527+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=3700 volume=100 May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.527+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status pause May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio sudo[10811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio sudo[10815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 26 20:54:04 volumio sudo[10815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio sudo[10813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status pause May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio sudo[10820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10813]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio sudo[10820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio sudo[10822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio sudo[10824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio sudo[10815]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio sudo[10811]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio sudo[10826]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 26 20:54:04 volumio sudo[10826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service canceled. May 26 20:54:04 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service canceled. May 26 20:54:04 volumio sudo[10822]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:04 volumio sudo[10824]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio volumio[7774]: info: touch_display: Setting screensaver timeout to 0 seconds. May 26 20:54:04 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:04 volumio sudo[10820]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio volumio[7774]: info: peppyspectrum Daemon Started May 26 20:54:04 volumio sudo[10831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... May 26 20:54:04 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. May 26 20:54:04 volumio systemd[1]: peppyspectrum.service: Failed with result 'start-limit-hit'. May 26 20:54:04 volumio systemd[1]: peppyspectrum.service: Unit process 10799 (python3) remains running after unit stopped. May 26 20:54:04 volumio sudo[10826]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio systemd[1]: Failed to start peppyspectrum.service - peppyspectrum Daemon. May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to stop!! Error: Command failed: /usr/bin/sudo /bin/systemctl stop peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service canceled. May 26 20:54:04 volumio sudo[10831]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:04 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:04 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:04 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:04 volumio volumio[7774]: info: Pushing metadata May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:04 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.837+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=3700 volume=100 May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.837+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:04 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:04 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.846+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=3700 volume=100 May 26 20:54:04 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:04.846+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio sudo[10838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:04 volumio sudo[10840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio sudo[10846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. May 26 20:54:04 volumio systemd[1]: peppyspectrum.service: Failed with result 'start-limit-hit'. May 26 20:54:04 volumio systemd[1]: Failed to start peppyspectrum.service - peppyspectrum Daemon. May 26 20:54:04 volumio sudo[10843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio sudo[10843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:04 volumio sudo[10840]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio sudo[10838]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio sudo[10846]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:04 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:04 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:04 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:04 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:04 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:04 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:04 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:04 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:04 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:04 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. May 26 20:54:04 volumio systemd[1]: peppyspectrum.service: Failed with result 'start-limit-hit'. May 26 20:54:04 volumio systemd[1]: Failed to start peppyspectrum.service - peppyspectrum Daemon. May 26 20:54:04 volumio sudo[10843]: pam_unix(sudo:session): session closed for user root May 26 20:54:04 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:04 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:04 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:04 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:04 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:05 volumio vtcs[10718]: [close:85] Entering May 26 20:54:05 volumio vtcs[10718]: [close:100] Exiting May 26 20:54:05 volumio vtcs[10718]: [open:50] Opening with sample rate: 44100, type: 0 May 26 20:54:05 volumio vtcs[10718]: [start:106] Entering May 26 20:54:05 volumio vtcs[10718]: [start:113] Reopening PCM device... May 26 20:54:05 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:05 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:05 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:05 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:05 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:05 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:05.121+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=3700 volume=100 May 26 20:54:05 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:05.121+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:05 volumio vtcs[10718]: [start:115] PCM opened successfully. May 26 20:54:05 volumio vtcs[10718]: [start:120] Exiting May 26 20:54:05 volumio vtcs[10718]: [feedThread:223] Entering May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:05 volumio sudo[10852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio sudo[10852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:05 volumio sudo[10854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio sudo[10854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:05 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. May 26 20:54:05 volumio systemd[1]: peppyspectrum.service: Failed with result 'start-limit-hit'. May 26 20:54:05 volumio systemd[1]: Failed to start peppyspectrum.service - peppyspectrum Daemon. May 26 20:54:05 volumio sudo[10854]: pam_unix(sudo:session): session closed for user root May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:05 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:05 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:05 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:05 volumio sudo[10852]: pam_unix(sudo:session): session closed for user root May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:05 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:05 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:05 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:05 volumio vtcs[10718]: [2026-05-26 20:54:05.213] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC May 26 20:54:05 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:05 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:05 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:05 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:05 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:05 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:05.224+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=3700 volume=100 May 26 20:54:05 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:05.224+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:05 volumio sudo[10858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio sudo[10858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:05 volumio sudo[10860]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio sudo[10860]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:05 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. May 26 20:54:05 volumio systemd[1]: peppyspectrum.service: Failed with result 'start-limit-hit'. May 26 20:54:05 volumio systemd[1]: Failed to start peppyspectrum.service - peppyspectrum Daemon. May 26 20:54:05 volumio sudo[10858]: pam_unix(sudo:session): session closed for user root May 26 20:54:05 volumio sudo[10860]: pam_unix(sudo:session): session closed for user root May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:05 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:05 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:05 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:05 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:05 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:05 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:05 volumio volumio[7774]: info: CoreCommandRouter::servicePushState May 26 20:54:05 volumio volumio[7774]: info: CoreStateMachine::pushState May 26 20:54:05 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 20:54:05 volumio volumio[7774]: info: CoreCommandRouter::volumioPushState May 26 20:54:05 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:05 volumio volumio[7774]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false May 26 20:54:05 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:05.628+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" state=STATUS_PLAYING positionMs=4215 volume=100 May 26 20:54:05 volumio volumio5-onboarding[2706]: time=2026-05-26T20:54:05.628+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.50.245:57225 @ 0x1d0ce40" id= title="Canon In D (Piano and Violin Duet)" May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum status play May 26 20:54:05 volumio sudo[10866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio sudo[10864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio sudo[10864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:05 volumio sudo[10866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 20:54:05 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. May 26 20:54:05 volumio systemd[1]: peppyspectrum.service: Failed with result 'start-limit-hit'. May 26 20:54:05 volumio systemd[1]: Failed to start peppyspectrum.service - peppyspectrum Daemon. May 26 20:54:05 volumio sudo[10866]: pam_unix(sudo:session): session closed for user root May 26 20:54:05 volumio sudo[10864]: pam_unix(sudo:session): session closed for user root May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:05 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:05 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:05 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:05 volumio volumio[7774]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service May 26 20:54:05 volumio volumio[7774]: Job for peppyspectrum.service failed because start of the service was attempted too often. May 26 20:54:05 volumio volumio[7774]: See "systemctl status peppyspectrum.service" and "journalctl -xeu peppyspectrum.service" for details. May 26 20:54:05 volumio volumio[7774]: To force a start use "systemctl reset-failed peppyspectrum.service" May 26 20:54:05 volumio volumio[7774]: followed by "systemctl start peppyspectrum.service" again. May 26 20:54:10 volumio vtcs[10718]: [2026-05-26 20:54:10] [info] asio async_shutdown error: asio.misc:2 (End of file) May 26 20:54:15 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 20:54:15 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 26 20:54:18 volumio volumio[7774]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---list is Random,G5_01Marshall,G5_02Kenwood,G5_03M6100 Gold,G5_04Naim,G5_05McIntosh,G5_06Advance,G5_07Marantz,G5_07Luxman,G5_08Sansui May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-custom May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1280x400+30-Gelo5 May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x100+99-99bars May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x480+20-marshall May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x480+230-230-bars-freq May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-Gelo5 May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x480+30-marshall May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x480+55-marshall May 26 20:54:18 volumio volumio[7774]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 26 20:54:43 volumio ntpd[1244]: PROTO: 91.212.242.21 unlink local addr 192.168.50.157 -> May 26 20:54:49 volumio volumio[7774]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] May 26 20:54:49 volumio volumio[7774]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum May 26 20:54:51 volumio volumio[7774]: --2026-05-26 20:54:49-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose May 26 20:54:51 volumio volumio[7774]: Resolving github.com (github.com)... 140.82.121.3 May 26 20:54:51 volumio volumio[7774]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. May 26 20:54:51 volumio volumio[7774]: HTTP request sent, awaiting response... 404 Not Found May 26 20:54:51 volumio volumio[7774]: 2026-05-26 20:54:50 ERROR 404: Not Found. May 26 20:54:51 volumio volumio[7774]: --2026-05-26 20:54:50-- http://a/ May 26 20:54:51 volumio volumio[7774]: Resolving a (a)... failed: Device or resource busy. May 26 20:54:51 volumio volumio[7774]: wget: unable to resolve host address ‘a’ May 26 20:54:51 volumio volumio[7774]: --2026-05-26 20:54:50-- http://packet.zip/ May 26 20:54:51 volumio volumio[7774]: Resolving packet.zip (packet.zip)... 162.255.119.167 May 26 20:54:51 volumio volumio[7774]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. May 26 20:54:51 volumio volumio[7774]: HTTP request sent, awaiting response... 302 Found May 26 20:54:51 volumio volumio[7774]: Location: http://www.packet.zip/ [following] May 26 20:54:51 volumio volumio[7774]: --2026-05-26 20:54:51-- http://www.packet.zip/ May 26 20:54:51 volumio volumio[7774]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 May 26 20:54:51 volumio volumio[7774]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. May 26 20:54:51 volumio volumio[7774]: HTTP request sent, awaiting response... 403 Forbidden May 26 20:54:51 volumio volumio[7774]: 2026-05-26 20:54:51 ERROR 403: Forbidden. May 26 20:54:51 volumio volumio[7774]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums May 26 20:54:51 volumio volumio[7774]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory May 26 20:54:51 volumio volumio[7774]: /bin/rm: cannot remove 'a': No such file or directory May 26 20:54:51 volumio volumio[7774]: /bin/rm: cannot remove 'packet.zip*': No such file or directory May 26 20:54:51 volumio volumio[7774]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 20:54:51 volumio volumio[7774]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* May 26 20:54:51 volumio volumio[7774]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory May 26 20:54:51 volumio volumio[7774]: /bin/rm: cannot remove 'a': No such file or directory May 26 20:54:51 volumio volumio[7774]: /bin/rm: cannot remove 'packet.zip*': No such file or directory May 26 20:54:51 volumio volumio[7774]: at checkExecSyncError (node:child_process:887:11) May 26 20:54:51 volumio volumio[7774]: at execSync (node:child_process:959:15) May 26 20:54:51 volumio volumio[7774]: at /data/plugins/user_interface/peppyspectrum/index.js:765:19 May 26 20:54:51 volumio volumio[7774]: at new Promise () May 26 20:54:51 volumio volumio[7774]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:740:12) May 26 20:54:51 volumio volumio[7774]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 26 20:54:51 volumio volumio[7774]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) May 26 20:54:51 volumio volumio[7774]: at Socket.emit (node:events:514:28) May 26 20:54:51 volumio volumio[7774]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 26 20:54:51 volumio volumio[7774]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { May 26 20:54:51 volumio volumio[7774]: status: 1, May 26 20:54:51 volumio volumio[7774]: signal: null, May 26 20:54:51 volumio volumio[7774]: output: [ May 26 20:54:51 volumio volumio[7774]: null, May 26 20:54:51 volumio volumio[7774]: , May 26 20:54:51 volumio volumio[7774]: May 26 20:54:51 volumio volumio[7774]: ], May 26 20:54:51 volumio volumio[7774]: pid: 10936, May 26 20:54:51 volumio volumio[7774]: stdout: , May 26 20:54:51 volumio volumio[7774]: stderr: May 26 20:54:51 volumio volumio[7774]: } May 26 20:54:51 volumio volumio[7774]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 20:54:51 volumio sudo[10952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 20:53' May 26 20:54:51 volumio sudo[10952]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"