-- Logs begin at Sat 2025-04-26 13:01:27 EDT, end at Sat 2025-04-26 13:07:05 EDT. -- Apr 26 13:06:00 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:00 volumio volumio[1376]: 2+0 records in Apr 26 13:06:00 volumio volumio[1376]: 2+0 records out Apr 26 13:06:00 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.0001935 s, 339 MB/s Apr 26 13:06:01 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:01 volumio volumio[1376]: 1+1 records in Apr 26 13:06:01 volumio volumio[1376]: 1+1 records out Apr 26 13:06:01 volumio volumio[1376]: 62976 bytes (63 kB, 62 KiB) copied, 0.000162648 s, 387 MB/s Apr 26 13:06:02 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:02 volumio volumio[1376]: 2+0 records in Apr 26 13:06:02 volumio volumio[1376]: 2+0 records out Apr 26 13:06:02 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000141925 s, 462 MB/s Apr 26 13:06:03 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:03 volumio volumio[1376]: 1+1 records in Apr 26 13:06:03 volumio volumio[1376]: 1+1 records out Apr 26 13:06:03 volumio volumio[1376]: 62336 bytes (62 kB, 61 KiB) copied, 0.0001385 s, 450 MB/s Apr 26 13:06:04 volumio volumio[7730]: libpng warning: iCCP: known incorrect sRGB profile Apr 26 13:06:04 volumio volumio[7730]: libpng warning: iCCP: known incorrect sRGB profile Apr 26 13:06:04 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:04 volumio volumio[1376]: 1+1 records in Apr 26 13:06:04 volumio volumio[1376]: 1+1 records out Apr 26 13:06:04 volumio volumio[1376]: 64912 bytes (65 kB, 63 KiB) copied, 0.000143037 s, 454 MB/s Apr 26 13:06:05 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:05 volumio volumio[1376]: 1+1 records in Apr 26 13:06:05 volumio volumio[1376]: 1+1 records out Apr 26 13:06:05 volumio volumio[1376]: 63280 bytes (63 kB, 62 KiB) copied, 0.000150222 s, 421 MB/s Apr 26 13:06:06 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:06 volumio volumio[1376]: 2+0 records in Apr 26 13:06:06 volumio volumio[1376]: 2+0 records out Apr 26 13:06:06 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000151629 s, 432 MB/s Apr 26 13:06:07 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:07 volumio volumio[1376]: 1+1 records in Apr 26 13:06:07 volumio volumio[1376]: 1+1 records out Apr 26 13:06:07 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000153 s, 410 MB/s Apr 26 13:06:08 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:08 volumio volumio[1376]: 1+1 records in Apr 26 13:06:08 volumio volumio[1376]: 1+1 records out Apr 26 13:06:08 volumio volumio[1376]: 64576 bytes (65 kB, 63 KiB) copied, 0.000160389 s, 403 MB/s Apr 26 13:06:09 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:09 volumio volumio[1376]: 2+0 records in Apr 26 13:06:09 volumio volumio[1376]: 2+0 records out Apr 26 13:06:09 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.00014937 s, 439 MB/s Apr 26 13:06:10 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:10 volumio volumio[1376]: 1+1 records in Apr 26 13:06:10 volumio volumio[1376]: 1+1 records out Apr 26 13:06:10 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000168778 s, 371 MB/s Apr 26 13:06:11 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:11 volumio volumio[1376]: 2+0 records in Apr 26 13:06:11 volumio volumio[1376]: 2+0 records out Apr 26 13:06:11 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000158592 s, 413 MB/s Apr 26 13:06:13 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:13 volumio volumio[1376]: 1+1 records in Apr 26 13:06:13 volumio volumio[1376]: 1+1 records out Apr 26 13:06:13 volumio volumio[1376]: 63040 bytes (63 kB, 62 KiB) copied, 0.000151704 s, 416 MB/s Apr 26 13:06:14 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:14 volumio volumio[1376]: 2+0 records in Apr 26 13:06:14 volumio volumio[1376]: 2+0 records out Apr 26 13:06:14 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000148852 s, 440 MB/s Apr 26 13:06:15 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:15 volumio volumio[1376]: 1+1 records in Apr 26 13:06:15 volumio volumio[1376]: 1+1 records out Apr 26 13:06:15 volumio volumio[1376]: 62272 bytes (62 kB, 61 KiB) copied, 0.000154574 s, 403 MB/s Apr 26 13:06:16 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:16 volumio volumio[1376]: 1+1 records in Apr 26 13:06:16 volumio volumio[1376]: 1+1 records out Apr 26 13:06:16 volumio volumio[1376]: 64976 bytes (65 kB, 63 KiB) copied, 0.000150796 s, 431 MB/s Apr 26 13:06:17 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:17 volumio volumio[1376]: 1+1 records in Apr 26 13:06:17 volumio volumio[1376]: 1+1 records out Apr 26 13:06:17 volumio volumio[1376]: 63216 bytes (63 kB, 62 KiB) copied, 0.000157111 s, 402 MB/s Apr 26 13:06:18 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:18 volumio volumio[1376]: 2+0 records in Apr 26 13:06:18 volumio volumio[1376]: 2+0 records out Apr 26 13:06:18 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000151056 s, 434 MB/s Apr 26 13:06:19 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:19 volumio volumio[1376]: 1+1 records in Apr 26 13:06:19 volumio volumio[1376]: 1+1 records out Apr 26 13:06:19 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000150574 s, 416 MB/s Apr 26 13:06:20 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:20 volumio volumio[1376]: 1+1 records in Apr 26 13:06:20 volumio volumio[1376]: 1+1 records out Apr 26 13:06:20 volumio volumio[1376]: 64576 bytes (65 kB, 63 KiB) copied, 0.000144889 s, 446 MB/s Apr 26 13:06:21 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:21 volumio volumio[1376]: 2+0 records in Apr 26 13:06:21 volumio volumio[1376]: 2+0 records out Apr 26 13:06:21 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000179574 s, 365 MB/s Apr 26 13:06:22 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:22 volumio volumio[1376]: 1+1 records in Apr 26 13:06:22 volumio volumio[1376]: 1+1 records out Apr 26 13:06:22 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000150019 s, 418 MB/s Apr 26 13:06:23 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:23 volumio volumio[1376]: 2+0 records in Apr 26 13:06:23 volumio volumio[1376]: 2+0 records out Apr 26 13:06:23 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000158037 s, 415 MB/s Apr 26 13:06:23 volumio volumio[1376]: verbose: New Socket.io Connection to 192.168.2.94 from 192.168.2.68 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Apr 26 13:06:23 volumio volumio[1376]: info: Received Get System Info Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 13:06:23 volumio volumio[1376]: info: Discovery: Getting this device information Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:23 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:23 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:23 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 13:06:23 volumio volumio[1376]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 26 13:06:23 volumio volumio[1376]: info: Received Get System Info Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 13:06:23 volumio volumio[1376]: info: Discovery: Getting this device information Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:23 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:23 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:23 volumio volumio[1376]: info: Listing playlists Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::volumioGetQueue Apr 26 13:06:23 volumio volumio[1376]: info: CoreStateMachine::getQueue Apr 26 13:06:23 volumio volumio[1376]: info: CorePlayQueue::getQueue Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:23 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:23 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:24 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:24 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:24 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:24 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:24 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:24 volumio volumio[1376]: 1+1 records in Apr 26 13:06:24 volumio volumio[1376]: 1+1 records out Apr 26 13:06:24 volumio volumio[1376]: 63104 bytes (63 kB, 62 KiB) copied, 0.000162574 s, 388 MB/s Apr 26 13:06:25 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:25 volumio volumio[1376]: 2+0 records in Apr 26 13:06:25 volumio volumio[1376]: 2+0 records out Apr 26 13:06:25 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000149278 s, 439 MB/s Apr 26 13:06:26 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:26 volumio volumio[1376]: 1+1 records in Apr 26 13:06:26 volumio volumio[1376]: 1+1 records out Apr 26 13:06:26 volumio volumio[1376]: 62208 bytes (62 kB, 61 KiB) copied, 0.000159019 s, 391 MB/s Apr 26 13:06:27 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:27 volumio volumio[1376]: 1+1 records in Apr 26 13:06:27 volumio volumio[1376]: 1+1 records out Apr 26 13:06:27 volumio volumio[1376]: 65040 bytes (65 kB, 64 KiB) copied, 0.00015213 s, 428 MB/s Apr 26 13:06:28 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:28 volumio volumio[1376]: 1+1 records in Apr 26 13:06:28 volumio volumio[1376]: 1+1 records out Apr 26 13:06:28 volumio volumio[1376]: 63152 bytes (63 kB, 62 KiB) copied, 0.000143463 s, 440 MB/s Apr 26 13:06:29 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:29 volumio volumio[1376]: 2+0 records in Apr 26 13:06:29 volumio volumio[1376]: 2+0 records out Apr 26 13:06:29 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000151408 s, 433 MB/s Apr 26 13:06:29 volumio volumio[1376]: info: VolumeController::SetAlsaVolume63 Apr 26 13:06:29 volumio volumio[1376]: info: CoreStateMachine::pushState Apr 26 13:06:29 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:29 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:06:29 volumio volumio[1376]: info: CoreCommandRouter::volumioPushState Apr 26 13:06:29 volumio volumio[1376]: info: [ASDebug] CurState: play PrevState: play Apr 26 13:06:29 volumio volumio[1376]: info: [GPIO_Control] Status changed: play Apr 26 13:06:29 volumio volumio[1376]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 26 13:06:29 volumio volumio[1376]: info: PeppySpectrum ---peppyspectrum status play Apr 26 13:06:29 volumio sudo[27828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 26 13:06:29 volumio sudo[27828]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:29 volumio volumio[1376]: SPOTIFY: RECEIVED VOLUMIO VOLUME 63 Apr 26 13:06:29 volumio volumio[1376]: SPOTIFY: SPOTIFY VOLUME 80 Apr 26 13:06:29 volumio volumio[1376]: SPOTIFY: VOLUMIO VOLUME 63 Apr 26 13:06:29 volumio volumio[1376]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 26 13:06:29 volumio volumio[1376]: info: Setting Spotify Volume from Volumio: 63 Apr 26 13:06:29 volumio sudo[27831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 26 13:06:29 volumio sudo[27831]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:29 volumio sudo[27828]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:29 volumio volumio[1376]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 26 13:06:29 volumio volumio[1376]: info: peppymeterbasic Daemon Started Apr 26 13:06:29 volumio sudo[27831]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:29 volumio volumio[1376]: info: peppyspectrum Daemon Started Apr 26 13:06:30 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:30 volumio volumio[1376]: 1+1 records in Apr 26 13:06:30 volumio volumio[1376]: 1+1 records out Apr 26 13:06:30 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000208463 s, 301 MB/s Apr 26 13:06:31 volumio volumio[1376]: SPOTIFY: SETTING SPOTIFY VOLUME 63 Apr 26 13:06:31 volumio volumio[1376]: info: Sending Spotify command with payload to local API: /player/volume Apr 26 13:06:31 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:31 volumio volumio[1376]: 1+1 records in Apr 26 13:06:31 volumio volumio[1376]: 1+1 records out Apr 26 13:06:31 volumio volumio[1376]: 64576 bytes (65 kB, 63 KiB) copied, 0.000147981 s, 436 MB/s Apr 26 13:06:31 volumio volumio[1376]: info: VolumeController::SetAlsaVolume72 Apr 26 13:06:31 volumio volumio[1376]: info: CoreStateMachine::pushState Apr 26 13:06:31 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:31 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:06:31 volumio volumio[1376]: info: CoreCommandRouter::volumioPushState Apr 26 13:06:31 volumio volumio[1376]: info: [ASDebug] CurState: play PrevState: play Apr 26 13:06:31 volumio volumio[1376]: info: [GPIO_Control] Status changed: play Apr 26 13:06:31 volumio volumio[1376]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 26 13:06:31 volumio volumio[1376]: info: PeppySpectrum ---peppyspectrum status play Apr 26 13:06:31 volumio sudo[27862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 26 13:06:31 volumio sudo[27862]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:31 volumio volumio[1376]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 26 13:06:31 volumio volumio[1376]: SPOTIFY: SPOTIFY VOLUME 63 Apr 26 13:06:31 volumio volumio[1376]: SPOTIFY: VOLUMIO VOLUME 72 Apr 26 13:06:31 volumio volumio[1376]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 26 13:06:31 volumio volumio[1376]: info: Setting Spotify Volume from Volumio: 72 Apr 26 13:06:31 volumio sudo[27865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 26 13:06:31 volumio sudo[27865]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:31 volumio volumio[1376]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 26 13:06:31 volumio sudo[27862]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:31 volumio volumio[1376]: info: peppymeterbasic Daemon Started Apr 26 13:06:31 volumio sudo[27865]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:31 volumio volumio[1376]: info: peppyspectrum Daemon Started Apr 26 13:06:32 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:32 volumio volumio[1376]: 2+0 records in Apr 26 13:06:32 volumio volumio[1376]: 2+0 records out Apr 26 13:06:32 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000148092 s, 443 MB/s Apr 26 13:06:33 volumio volumio[1376]: SPOTIFY: SETTING SPOTIFY VOLUME 72 Apr 26 13:06:33 volumio volumio[1376]: info: Sending Spotify command with payload to local API: /player/volume Apr 26 13:06:33 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:33 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:33 volumio volumio[1376]: 1+1 records in Apr 26 13:06:33 volumio volumio[1376]: 1+1 records out Apr 26 13:06:33 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000144055 s, 435 MB/s Apr 26 13:06:34 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:34 volumio volumio[1376]: 2+0 records in Apr 26 13:06:34 volumio volumio[1376]: 2+0 records out Apr 26 13:06:34 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000150481 s, 436 MB/s Apr 26 13:06:35 volumio volumio[1376]: verbose: New Socket.io Connection to 192.168.2.94 from 192.168.2.68 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Apr 26 13:06:35 volumio volumio[1376]: info: Received Get System Info Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 13:06:35 volumio volumio[1376]: info: Discovery: Getting this device information Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:35 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:35 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:35 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 13:06:35 volumio volumio[1376]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 26 13:06:35 volumio volumio[1376]: info: Received Get System Info Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 13:06:35 volumio volumio[1376]: info: Discovery: Getting this device information Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:35 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:06:35 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:35 volumio volumio[1376]: info: Listing playlists Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::volumioGetQueue Apr 26 13:06:35 volumio volumio[1376]: info: CoreStateMachine::getQueue Apr 26 13:06:35 volumio volumio[1376]: info: CorePlayQueue::getQueue Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:35 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - IP adress is ---------------------------192.168.2.94 Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:35 volumio volumio[1376]: info: FusionDsp - undefined Apr 26 13:06:35 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:35 volumio volumio[1376]: 1+1 records in Apr 26 13:06:35 volumio volumio[1376]: 1+1 records out Apr 26 13:06:35 volumio volumio[1376]: 63168 bytes (63 kB, 62 KiB) copied, 0.00027224 s, 232 MB/s Apr 26 13:06:36 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:36 volumio volumio[1376]: 2+0 records in Apr 26 13:06:36 volumio volumio[1376]: 2+0 records out Apr 26 13:06:36 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000154907 s, 423 MB/s Apr 26 13:06:37 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:37 volumio volumio[1376]: 1+1 records in Apr 26 13:06:37 volumio volumio[1376]: 1+1 records out Apr 26 13:06:37 volumio volumio[1376]: 62144 bytes (62 kB, 61 KiB) copied, 0.000153018 s, 406 MB/s Apr 26 13:06:38 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:38 volumio volumio[1376]: 1+1 records in Apr 26 13:06:38 volumio volumio[1376]: 1+1 records out Apr 26 13:06:38 volumio volumio[1376]: 65104 bytes (65 kB, 64 KiB) copied, 0.000147241 s, 442 MB/s Apr 26 13:06:39 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:39 volumio volumio[1376]: 1+1 records in Apr 26 13:06:39 volumio volumio[1376]: 1+1 records out Apr 26 13:06:39 volumio volumio[1376]: 63088 bytes (63 kB, 62 KiB) copied, 0.000149482 s, 422 MB/s Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 13:06:40 volumio volumio[1376]: info: Getting Alsa Cards List without I2S DAC Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: Invalid card number. Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 26 13:06:40 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 13:06:40 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:40 volumio volumio[1376]: 2+0 records in Apr 26 13:06:40 volumio volumio[1376]: 2+0 records out Apr 26 13:06:40 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000146723 s, 447 MB/s Apr 26 13:06:41 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:41 volumio volumio[1376]: 1+1 records in Apr 26 13:06:41 volumio volumio[1376]: 1+1 records out Apr 26 13:06:41 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.00015987 s, 392 MB/s Apr 26 13:06:42 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:42 volumio volumio[1376]: 1+1 records in Apr 26 13:06:42 volumio volumio[1376]: 1+1 records out Apr 26 13:06:42 volumio volumio[1376]: 64576 bytes (65 kB, 63 KiB) copied, 0.000154407 s, 418 MB/s Apr 26 13:06:43 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:43 volumio volumio[1376]: 2+0 records in Apr 26 13:06:43 volumio volumio[1376]: 2+0 records out Apr 26 13:06:43 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000119759 s, 547 MB/s Apr 26 13:06:44 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:44 volumio volumio[1376]: 1+1 records in Apr 26 13:06:44 volumio volumio[1376]: 1+1 records out Apr 26 13:06:44 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000137686 s, 455 MB/s Apr 26 13:06:45 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 13:06:45 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:45 volumio volumio[1376]: 2+0 records in Apr 26 13:06:45 volumio volumio[1376]: 2+0 records out Apr 26 13:06:45 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.0001615 s, 406 MB/s Apr 26 13:06:47 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:47 volumio volumio[1376]: 1+1 records in Apr 26 13:06:47 volumio volumio[1376]: 1+1 records out Apr 26 13:06:47 volumio volumio[1376]: 63232 bytes (63 kB, 62 KiB) copied, 0.000141148 s, 448 MB/s Apr 26 13:06:48 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:48 volumio volumio[1376]: 2+0 records in Apr 26 13:06:48 volumio volumio[1376]: 2+0 records out Apr 26 13:06:48 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000153204 s, 428 MB/s Apr 26 13:06:49 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:49 volumio volumio[1376]: 1+1 records in Apr 26 13:06:49 volumio volumio[1376]: 1+1 records out Apr 26 13:06:49 volumio volumio[1376]: 62080 bytes (62 kB, 61 KiB) copied, 0.000147796 s, 420 MB/s Apr 26 13:06:50 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:50 volumio volumio[1376]: 1+1 records in Apr 26 13:06:50 volumio volumio[1376]: 1+1 records out Apr 26 13:06:50 volumio volumio[1376]: 65168 bytes (65 kB, 64 KiB) copied, 0.000146 s, 446 MB/s Apr 26 13:06:51 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:51 volumio volumio[1376]: 1+1 records in Apr 26 13:06:51 volumio volumio[1376]: 1+1 records out Apr 26 13:06:51 volumio volumio[1376]: 63024 bytes (63 kB, 62 KiB) copied, 0.000150704 s, 418 MB/s Apr 26 13:06:52 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:52 volumio volumio[1376]: 2+0 records in Apr 26 13:06:52 volumio volumio[1376]: 2+0 records out Apr 26 13:06:52 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.0001685 s, 389 MB/s Apr 26 13:06:53 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:53 volumio volumio[1376]: 1+1 records in Apr 26 13:06:53 volumio volumio[1376]: 1+1 records out Apr 26 13:06:53 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000149055 s, 420 MB/s Apr 26 13:06:54 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:54 volumio volumio[1376]: 1+1 records in Apr 26 13:06:54 volumio volumio[1376]: 1+1 records out Apr 26 13:06:54 volumio volumio[1376]: 64576 bytes (65 kB, 63 KiB) copied, 0.000157241 s, 411 MB/s Apr 26 13:06:55 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:55 volumio volumio[1376]: 2+0 records in Apr 26 13:06:55 volumio volumio[1376]: 2+0 records out Apr 26 13:06:55 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000145351 s, 451 MB/s Apr 26 13:06:56 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:56 volumio volumio[1376]: 1+1 records in Apr 26 13:06:56 volumio volumio[1376]: 1+1 records out Apr 26 13:06:56 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000144852 s, 433 MB/s Apr 26 13:06:57 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:57 volumio volumio[1376]: 2+0 records in Apr 26 13:06:57 volumio volumio[1376]: 2+0 records out Apr 26 13:06:57 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000159741 s, 410 MB/s Apr 26 13:06:57 volumio volumio[1376]: info: [1745687217983] [80s80s] Pushing the next song state Rakim - When I B on tha Mic and getting next track. Apr 26 13:06:57 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:57 volumio volumio[1376]: info: CoreCommandRouter::servicePushState Apr 26 13:06:57 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:57 volumio volumio[1376]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/20/d0/ac/20d0acb1-e609-edc0-e8f5-466cb98f86f5/source/600x600bb.jpg","name":"Rakim - When I B on tha Mic","title":"When I B on tha Mic","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"218","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 26 13:06:57 volumio volumio[1376]: verbose: CURRENT POSITION 0 Apr 26 13:06:57 volumio volumio[1376]: info: CoreStateMachine::syncState stateService play Apr 26 13:06:57 volumio volumio[1376]: info: CoreStateMachine::syncState currentStatus play Apr 26 13:06:57 volumio volumio[1376]: info: Received an update from plugin. extracting info from payload Apr 26 13:06:57 volumio volumio[1376]: info: CoreStateMachine::pushState Apr 26 13:06:57 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:57 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:06:57 volumio volumio[1376]: info: CoreCommandRouter::volumioPushState Apr 26 13:06:57 volumio volumio[1376]: info: CoreStateMachine::pushState Apr 26 13:06:57 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:57 volumio volumio[1376]: info: CoreCommandRouter::volumioPushState Apr 26 13:06:58 volumio volumio[1376]: info: [1745687218000] [80s80s] getContentOfUrl started with url http://iris-90s90s.loverad.io/flow.json?station=265&count=1 Apr 26 13:06:58 volumio volumio[1376]: info: [ASDebug] CurState: play PrevState: play Apr 26 13:06:58 volumio volumio[1376]: info: [ASDebug] CurState: play PrevState: play Apr 26 13:06:58 volumio volumio[1376]: info: [GPIO_Control] Status changed: play Apr 26 13:06:58 volumio volumio[1376]: info: [GPIO_Control] Status changed: play Apr 26 13:06:58 volumio volumio[1376]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 26 13:06:58 volumio volumio[1376]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 26 13:06:58 volumio sudo[28034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 26 13:06:58 volumio sudo[28034]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:58 volumio volumio[1376]: info: PeppySpectrum ---peppyspectrum status play Apr 26 13:06:58 volumio sudo[28037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 26 13:06:58 volumio sudo[28034]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:58 volumio sudo[28037]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:58 volumio volumio[1376]: info: PeppySpectrum ---peppyspectrum status play Apr 26 13:06:58 volumio sudo[28037]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:58 volumio sudo[28040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 26 13:06:58 volumio sudo[28040]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:58 volumio volumio[1376]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 26 13:06:58 volumio volumio[1376]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 26 13:06:58 volumio sudo[28043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 26 13:06:58 volumio sudo[28043]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:06:58 volumio volumio[1376]: info: peppymeterbasic Daemon Started Apr 26 13:06:58 volumio sudo[28040]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:58 volumio volumio[1376]: info: peppyspectrum Daemon Started Apr 26 13:06:58 volumio sudo[28043]: pam_unix(sudo:session): session closed for user root Apr 26 13:06:58 volumio volumio[1376]: info: peppyspectrum Daemon Started Apr 26 13:06:58 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:06:58 volumio volumio[1376]: info: CorePlayQueue::getTrack 1 Apr 26 13:06:58 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:58 volumio volumio[1376]: 1+1 records in Apr 26 13:06:58 volumio volumio[1376]: 1+1 records out Apr 26 13:06:58 volumio volumio[1376]: 63296 bytes (63 kB, 62 KiB) copied, 0.00014537 s, 435 MB/s Apr 26 13:06:58 volumio volumio[1376]: info: [1745687218376] [80s80s] received new event containing 1 songs. Apr 26 13:06:58 volumio volumio[1376]: info: [1745687218377] [80s80s] PlayNextTrack API delay: 30 Apr 26 13:06:58 volumio volumio[1376]: info: [1745687218382] [80s80s] Setting timer to: 218000 milliseconds. Apr 26 13:06:59 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:06:59 volumio volumio[1376]: 2+0 records in Apr 26 13:06:59 volumio volumio[1376]: 2+0 records out Apr 26 13:06:59 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000144315 s, 454 MB/s Apr 26 13:07:00 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:07:00 volumio volumio[1376]: 1+1 records in Apr 26 13:07:00 volumio volumio[1376]: 1+1 records out Apr 26 13:07:00 volumio volumio[1376]: 62016 bytes (62 kB, 61 KiB) copied, 0.000145666 s, 426 MB/s Apr 26 13:07:01 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:07:01 volumio volumio[1376]: 1+1 records in Apr 26 13:07:01 volumio volumio[1376]: 1+1 records out Apr 26 13:07:01 volumio volumio[1376]: 65232 bytes (65 kB, 64 KiB) copied, 0.000141333 s, 462 MB/s Apr 26 13:07:02 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:07:02 volumio volumio[1376]: 1+1 records in Apr 26 13:07:02 volumio volumio[1376]: 1+1 records out Apr 26 13:07:02 volumio volumio[1376]: 62960 bytes (63 kB, 61 KiB) copied, 0.000142537 s, 442 MB/s Apr 26 13:07:03 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:07:03 volumio volumio[1376]: 2+0 records in Apr 26 13:07:03 volumio volumio[1376]: 2+0 records out Apr 26 13:07:03 volumio volumio[1376]: 65536 bytes (66 kB, 64 KiB) copied, 0.000150444 s, 436 MB/s Apr 26 13:07:03 volumio volumio[1376]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 26 13:07:03 volumio volumio[1376]: info: Preparing to save Alsa Options, stopping services first Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:07:03 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::volumioPause Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::pause Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::stPlaybackTimer Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::servicePause Apr 26 13:07:03 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::servicePause Apr 26 13:07:03 volumio volumio[1376]: verbose: ControllerMpd::sendMpdCommand pause Apr 26 13:07:03 volumio volumio[1376]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"iqaudio-amp","label":"IQaudIO Pi-DigiAMP+"}} Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: Enabling I2S DAC: IQaudIO Pi-DigiAMP+ Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio sudo[28092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 26 13:07:03 volumio sudo[28092]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio sudo[28092]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio volumio[1376]: info: No Overlays Loaded Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio sudo[28095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay iqaudio-dacplus Apr 26 13:07:03 volumio sudo[28095]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2s@a0000/status Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/status Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/pcm5122@4c/#sound-dai-cells Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/pcm5122@4c/compatible Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/pcm5122@4c/reg Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/pcm5122@4c/AVDD-supply Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/pcm5122@4c/DVDD-supply Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/pcm5122@4c/CPVDD-supply Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@120000/rp1/i2c@74000/pcm5122@4c/status Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/mute-gpios Apr 26 13:07:03 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Apr 26 13:07:03 volumio sudo[28095]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio volumio[1376]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: Found match in i2s Card Database: setting mixer Digital for card IQaudIO Pi-DigiAMP+ Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 26 13:07:03 volumio volumio[1376]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 26 13:07:03 volumio volumio[1376]: info: Disabling external Volume Control Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:07:03 volumio volumio[1376]: info: Enable softmixer device for audio device undefined Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 26 13:07:03 volumio volumio[1376]: info: Apr 26 13:07:03 volumio volumio[1376]: ---------------------------- MPD announces state update: player Apr 26 13:07:03 volumio volumio[1376]: info: sendMpdCommand pause took 161 milliseconds Apr 26 13:07:03 volumio volumio[1376]: info: ControllerMpd::getState Apr 26 13:07:03 volumio volumio[1376]: verbose: ControllerMpd::sendMpdCommand status Apr 26 13:07:03 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::servicePushState Apr 26 13:07:03 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:03 volumio volumio[1376]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music115/v4/20/d0/ac/20d0acb1-e609-edc0-e8f5-466cb98f86f5/source/600x600bb.jpg","name":"Rakim - When I B on tha Mic","title":"When I B on tha Mic","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"218","seek":5423,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 26 13:07:03 volumio volumio[1376]: verbose: CURRENT POSITION 0 Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::syncState stateService pause Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::syncState currentStatus pause Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::pushState Apr 26 13:07:03 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::volumioPushState Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::stPlaybackTimer Apr 26 13:07:03 volumio volumio[1376]: info: [ASDebug] CurState: pause PrevState: play Apr 26 13:07:03 volumio volumio[1376]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 26 13:07:03 volumio volumio[1376]: info: [GPIO_Control] Status changed: pause Apr 26 13:07:03 volumio volumio[1376]: info: [GPIO_Control] Handling event: musicPause Apr 26 13:07:03 volumio volumio[1376]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:07:03 volumio volumio[1376]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:07:03 volumio sudo[28115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:07:03 volumio sudo[28115]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio volumio[1376]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 26 13:07:03 volumio sudo[28118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:07:03 volumio volumio[1376]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Apr 26 13:07:03 volumio sudo[28118]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio systemd[1]: Stopping peppymeterbasic Daemon... Apr 26 13:07:03 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Apr 26 13:07:03 volumio volumio[1376]: error: Cannot execute DAC script: Error: Command failed: /volumio/app/plugins/system_controller/i2s_dacs/scripts/iqamp-unmute.sh Apr 26 13:07:03 volumio volumio[1376]: Oops: Unable to determine board revision from /proc/cpuinfo Apr 26 13:07:03 volumio volumio[1376]: -> No "Hardware" line Apr 26 13:07:03 volumio volumio[1376]: -> You'd best google the error to find out why. Apr 26 13:07:03 volumio volumio[1376]: Oops: Unable to determine board revision from /proc/cpuinfo Apr 26 13:07:03 volumio volumio[1376]: -> No "Hardware" line Apr 26 13:07:03 volumio volumio[1376]: -> You'd best google the error to find out why. Apr 26 13:07:03 volumio volumio[1376]: info: I2S Param [object Object] successfully enabled Apr 26 13:07:03 volumio systemd[1]: Stopping peppyspectrum Daemon... Apr 26 13:07:03 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Apr 26 13:07:03 volumio systemd[1]: peppyspectrum.service: Succeeded. Apr 26 13:07:03 volumio systemd[1]: Stopped peppyspectrum Daemon. Apr 26 13:07:03 volumio sudo[28118]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio volumio[1376]: info: sendMpdCommand status took 71 milliseconds Apr 26 13:07:03 volumio volumio[1376]: verbose: ControllerMpd::parseState Apr 26 13:07:03 volumio volumio[1376]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 13:07:03 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:03 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:03 volumio volumio[1376]: info: Software Volume ALSA configuration written Apr 26 13:07:03 volumio volumio[1376]: info: Preparing to generate the ALSA configuration file Apr 26 13:07:03 volumio volumio[1376]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 26 13:07:03 volumio volumio[1376]: verbose: ControllerMpd::parseTrackInfo Apr 26 13:07:03 volumio volumio[1376]: info: ControllerMpd::pushState Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::servicePushState Apr 26 13:07:03 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:03 volumio volumio[1376]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Apr 26 13:07:03 volumio volumio[1376]: info: ------------------------------ 92ms Apr 26 13:07:03 volumio volumio[1376]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 26 13:07:03 volumio volumio[1376]: info: peppyspectrum Daemon Stop Apr 26 13:07:03 volumio volumio[1376]: info: VolumeController:: Volume=undefined Mute =false Apr 26 13:07:03 volumio volumio[1376]: info: CoreStateMachine::pushState Apr 26 13:07:03 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:03 volumio systemd[1]: peppymeterbasic.service: Succeeded. Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:07:03 volumio systemd[1]: Stopped peppymeterbasic Daemon. Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::volumioPushState Apr 26 13:07:03 volumio sudo[28115]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 13:07:03 volumio volumio[1376]: info: Getting Alsa Cards List without I2S DAC Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: Invalid card number. Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 13:07:03 volumio volumio[1376]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 26 13:07:03 volumio volumio[1376]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 26 13:07:03 volumio volumio[1376]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 26 13:07:03 volumio volumio[1376]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 26 13:07:03 volumio volumio[1376]: info: Reading ALSA contributions from plugins. Apr 26 13:07:03 volumio volumio[1376]: info: [ASDebug] CurState: pause PrevState: play Apr 26 13:07:03 volumio volumio[1376]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 26 13:07:03 volumio volumio[1376]: info: [GPIO_Control] Status changed: pause Apr 26 13:07:03 volumio volumio[1376]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:07:03 volumio volumio[1376]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:07:03 volumio sudo[28134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:07:03 volumio sudo[28134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio volumio[1376]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 26 13:07:03 volumio volumio[1376]: info: peppymeterbasic Daemon Stop Apr 26 13:07:03 volumio sudo[28137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:07:03 volumio sudo[28134]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio sudo[28137]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio sudo[28137]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio volumio[1376]: info: peppymeterbasic Daemon Stop Apr 26 13:07:03 volumio volumio[1376]: info: peppyspectrum Daemon Stop Apr 26 13:07:03 volumio volumio[1376]: info: Asound.conf file written Apr 26 13:07:03 volumio sudo[28140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 26 13:07:03 volumio sudo[28140]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio sudo[28140]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio volumio[1376]: info: Output device has changed, restarting MPD Apr 26 13:07:03 volumio sudo[28145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 13:07:03 volumio sudo[28145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio volumio[1376]: info: Output device has changed, restarting Shairport Sync Apr 26 13:07:03 volumio sudo[28145]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:03 volumio sudo[28149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 13:07:03 volumio sudo[28149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:03 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 26 13:07:03 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 26 13:07:03 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 26 13:07:03 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 13:07:03 volumio systemd[1]: mpd.service: Succeeded. Apr 26 13:07:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 13:07:03 volumio volumio[1376]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 13:07:03 volumio volumio[1376]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 13:07:03 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:03 volumio vtcs[5734]: [2025-04-26 13:07:03.971] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 26 13:07:03 volumio vtcs[5734]: [2025-04-26 13:07:03.973] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Apr 26 13:07:03 volumio vtcs[5734]: [2025-04-26 13:07:03.973] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 26 13:07:03 volumio systemd[1]: Starting Music Player Daemon... Apr 26 13:07:03 volumio volumio[1376]: info: Output device has changed, restarting MPD Apr 26 13:07:03 volumio sudo[28158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 13:07:03 volumio sudo[28158]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio sudo[28161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 13:07:04 volumio sudo[28161]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio sudo[28161]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Apr 26 13:07:04 volumio sudo[28154]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 13:07:04 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 26 13:07:04 volumio sudo[28154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio systemd[1]: vtcs.service: Succeeded. Apr 26 13:07:04 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Apr 26 13:07:04 volumio volumio[1376]: info: Output device has changed, restarting Shairport Sync Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:04 volumio sudo[28164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 13:07:04 volumio sudo[28154]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio sudo[28164]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio sudo[28158]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 26 13:07:04 volumio systemd[1]: mpd.service: Succeeded. Apr 26 13:07:04 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 13:07:04 volumio systemd[1]: Starting Music Player Daemon... Apr 26 13:07:04 volumio volumio[1376]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 13:07:04 volumio volumio[1376]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio sudo[28172]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 13:07:04 volumio sudo[28172]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio sudo[28172]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio sudo[28180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 13:07:04 volumio sudo[28180]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio sudo[28179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 13:07:04 volumio sudo[28179]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio sudo[28180]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio sudo[28184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 13:07:04 volumio sudo[28184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio sudo[28179]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio sudo[28184]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio volumio[1376]: info: MPD Permissions set Apr 26 13:07:04 volumio volumio[1376]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 26 13:07:04 volumio volumio[1376]: info: MPD Permissions set Apr 26 13:07:04 volumio volumio[1376]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 13:07:04 volumio volumio[1376]: info: Starting Shairport Sync Apr 26 13:07:04 volumio volumio[1376]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:07:04 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:04 volumio volumio[1376]: info: mpdhttpout --- Volumio set on pause Apr 26 13:07:04 volumio volumio[1376]: info: Starting Shairport Sync Apr 26 13:07:04 volumio sudo[28198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 13:07:04 volumio sudo[28198]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 13:07:04 volumio volumio[1376]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 26 13:07:04 volumio volumio[1376]: info: CoreCommandRouter::volumioGetState Apr 26 13:07:04 volumio volumio[1376]: info: CorePlayQueue::getTrack 0 Apr 26 13:07:04 volumio volumio[1376]: info: mpdhttpout --- Volumio set on pause Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio sudo[28201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 13:07:04 volumio sudo[28201]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:07:04 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 13:07:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:07:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 13:07:04 volumio sudo[28198]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 13:07:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 26 13:07:04 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 13:07:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 13:07:04 volumio sudo[28201]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:07:04 volumio volumio[1376]: info: Shairport-Sync Started Apr 26 13:07:04 volumio volumio[1376]: info: Shairport-Sync Started Apr 26 13:07:04 volumio mpd[28176]: Apr 26 13:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 13:07:04 volumio systemd[1]: Started Music Player Daemon. Apr 26 13:07:04 volumio sudo[28149]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio sudo[28164]: pam_unix(sudo:session): session closed for user root Apr 26 13:07:04 volumio volumio[1376]: error: MPD error: The expression evaluated to a falsy value: Apr 26 13:07:04 volumio volumio[1376]: assert.ok(self.idling) Apr 26 13:07:04 volumio volumio[1376]: error: The expression evaluated to a falsy value: Apr 26 13:07:04 volumio volumio[1376]: assert.ok(self.idling) Apr 26 13:07:04 volumio volumio[1376]: error: updateQueue error: null Apr 26 13:07:04 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 26 13:07:04 volumio volumio[1376]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:07:04 volumio volumio[1376]: info: [ASDebug] Togle GPIO: OFF Apr 26 13:07:04 volumio volumio[28209]: cset: --> shielding system active with Apr 26 13:07:04 volumio volumio[28209]: cset: "system" cpuset of CPUSPEC(1-3) with 298 tasks running Apr 26 13:07:04 volumio volumio[28209]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Apr 26 13:07:04 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 26 13:07:04 volumio volumio[1376]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:07:04 volumio volumio[1376]: 1+1 records in Apr 26 13:07:04 volumio volumio[1376]: 1+1 records out Apr 26 13:07:04 volumio volumio[1376]: 62656 bytes (63 kB, 61 KiB) copied, 0.000127481 s, 491 MB/s Apr 26 13:07:05 volumio volumio[1376]: 0+0 records in Apr 26 13:07:05 volumio volumio[1376]: 0+0 records out Apr 26 13:07:05 volumio volumio[1376]: 0 bytes copied, 4.7963e-05 s, 0.0 kB/s Apr 26 13:07:05 volumio volumio[1376]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Apr 26 13:07:05 volumio volumio[1376]: info: PLUGIN onReboot : networkfs Apr 26 13:07:05 volumio volumio[1376]: info: PLUGIN onReboot : audiophonicsonoff Apr 26 13:07:05 volumio volumio[1376]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 13:07:05 volumio volumio[1376]: TypeError: Cannot read property 'writeSync' of undefined Apr 26 13:07:05 volumio volumio[1376]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Apr 26 13:07:05 volumio volumio[1376]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Apr 26 13:07:05 volumio volumio[1376]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Apr 26 13:07:05 volumio volumio[1376]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 26 13:07:05 volumio volumio[1376]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 26 13:07:05 volumio volumio[1376]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Apr 26 13:07:05 volumio volumio[1376]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Apr 26 13:07:05 volumio volumio[1376]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Apr 26 13:07:05 volumio volumio[1376]: at Socket.emit (events.js:315:20) Apr 26 13:07:05 volumio volumio[1376]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 26 13:07:05 volumio volumio[1376]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 26 13:07:05 volumio volumio[1376]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 13:07:05 volumio sudo[28243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 13:06 Apr 26 13:07:05 volumio sudo[28243]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"