-- Logs begin at Tue 2025-11-25 07:26:36 CET, end at Sat 2025-12-06 15:33:44 CET. -- Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:17 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:18 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:26 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:34 usbridge volumio[833]: info: Updating volume from AirPlay: -9.38; 69% Dec 06 15:32:34 usbridge volumio[833]: info: VolumeController::SetAlsaVolume69 Dec 06 15:32:34 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:34 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:34 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:34 usbridge volumio[833]: info: Updating volume from AirPlay: -7.5; 75% Dec 06 15:32:34 usbridge volumio[833]: info: VolumeController::SetAlsaVolume75 Dec 06 15:32:34 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:34 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:35 usbridge volumio[833]: info: Updating volume from AirPlay: -5.62; 81% Dec 06 15:32:35 usbridge volumio[833]: info: VolumeController::SetAlsaVolume81 Dec 06 15:32:35 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:35 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:35 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:36 usbridge volumio[833]: info: Updating volume from AirPlay: -3.75; 88% Dec 06 15:32:36 usbridge volumio[833]: info: VolumeController::SetAlsaVolume88 Dec 06 15:32:36 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:36 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:36 usbridge volumio[833]: info: Updating volume from AirPlay: -1.88; 94% Dec 06 15:32:36 usbridge volumio[833]: info: VolumeController::SetAlsaVolume94 Dec 06 15:32:36 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:36 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:36 usbridge volumio[833]: info: Updating volume from AirPlay: 0; 100% Dec 06 15:32:36 usbridge volumio[833]: info: VolumeController::SetAlsaVolume100 Dec 06 15:32:36 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:36 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:32:41 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::servicePushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:14 usbridge shairport-sync[1073]: {"time":1765031515429,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Dec 06 15:33:14 usbridge shairport-sync[1073]: Dload Upload Total Spent Left Speed Dec 06 15:33:14 usbridge volumio[833]: info: Dec 06 15:33:14 usbridge volumio[833]: ---------------------------- Client requests Stop Airplay Playback Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Dec 06 15:33:14 usbridge volumio[833]: info: Airplay playback stop Dec 06 15:33:14 usbridge volumio[833]: info: Airplay Playback Stopped, clearing state Dec 06 15:33:14 usbridge volumio[833]: info: CoreStateMachine::resetVolumioState Dec 06 15:33:14 usbridge volumio[833]: info: CoreStateMachine::getcurrentVolume Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 15:33:14 usbridge shairport-sync[1073]: [158B blob data] Dec 06 15:33:14 usbridge volumio[833]: info: CoreStateMachine::pushState Dec 06 15:33:14 usbridge volumio[833]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:17 usbridge volumio[833]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.169 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 4 Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::volumioGetState Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 15:33:18 usbridge volumio[833]: info: Received Get System Info Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 15:33:18 usbridge volumio[833]: info: Discovery: Getting this device information Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::volumioGetState Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::volumioGetState Dec 06 15:33:18 usbridge volumio[833]: info: Listing playlists Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::volumioGetQueue Dec 06 15:33:18 usbridge volumio[833]: info: CoreStateMachine::getQueue Dec 06 15:33:18 usbridge volumio[833]: info: CorePlayQueue::getQueue Dec 06 15:33:18 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 15:33:20 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 15:33:20 usbridge volumio[833]: info: Received Get System Info Dec 06 15:33:20 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 15:33:20 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 15:33:20 usbridge volumio[833]: info: Discovery: Getting this device information Dec 06 15:33:20 usbridge volumio[833]: info: CoreCommandRouter::volumioGetState Dec 06 15:33:20 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 15:33:29 usbridge volumio[833]: Searching plugin music_service/mpd Dec 06 15:33:29 usbridge volumio[833]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 06 15:33:30 usbridge volumio[833]: info: All search sources collected, pushing search results Dec 06 15:33:32 usbridge volumio[833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 15:33:32 usbridge volumio[833]: TypeError: Cannot read property 'slice' of null Dec 06 15:33:32 usbridge volumio[833]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 06 15:33:32 usbridge volumio[833]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 06 15:33:32 usbridge volumio[833]: at Object.onceWrapper (events.js:422:26) Dec 06 15:33:32 usbridge volumio[833]: at ClientRequest.emit (events.js:315:20) Dec 06 15:33:32 usbridge volumio[833]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 06 15:33:32 usbridge volumio[833]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 06 15:33:32 usbridge volumio[833]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 06 15:33:32 usbridge volumio[833]: at TLSSocket.emit (events.js:315:20) Dec 06 15:33:32 usbridge volumio[833]: at addChunk (internal/streams/readable.js:309:12) Dec 06 15:33:32 usbridge volumio[833]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 06 15:33:32 usbridge volumio[833]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 06 15:33:32 usbridge volumio[833]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 06 15:33:32 usbridge volumio[833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 15:33:33 usbridge sudo[19553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 15:32 Dec 06 15:33:33 usbridge sudo[19553]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:33 usbridge sudo[19553]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:34 usbridge volumio-remote-updater[506]: [2025-12-06 15:33:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 06 15:33:34 usbridge systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 06 15:33:34 usbridge volumio-remote-updater[506]: [2025-12-06 15:33:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 06 15:33:34 usbridge systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 06 15:33:34 usbridge systemd[1]: Started dynamicswap service. Dec 06 15:33:34 usbridge systemd[1]: dynamicswap.service: Succeeded. Dec 06 15:33:34 usbridge systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 06 15:33:34 usbridge systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 06 15:33:34 usbridge systemd[1]: Stopped Volumio Backend Module. Dec 06 15:33:34 usbridge systemd[1]: Started Volumio Backend Module. Dec 06 15:33:34 usbridge systemd[1]: Started dynamicswap service. Dec 06 15:33:34 usbridge systemd[1]: dynamicswap.service: Succeeded. Dec 06 15:33:36 usbridge volumio[19566]: info: ------------------------------------------- Dec 06 15:33:36 usbridge volumio[19566]: info: ----- Volumio3 ---- Dec 06 15:33:36 usbridge volumio[19566]: info: ------------------------------------------- Dec 06 15:33:36 usbridge volumio[19566]: info: ----- System startup ---- Dec 06 15:33:36 usbridge volumio[19566]: info: ------------------------------------------- Dec 06 15:33:37 usbridge volumio[19566]: info: MYVOLUMIO Environment detected Dec 06 15:33:37 usbridge volumio[19566]: info: Plugin folders cleanup Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning into folder /volumio/app/plugins/ Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning category audio_interface Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning category miscellanea Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning category music_service Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning category plugins.json Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning category system_controller Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning category user_interface Dec 06 15:33:37 usbridge volumio[19566]: info: Scanning into folder /data/plugins/ Dec 06 15:33:37 usbridge volumio[19566]: info: Plugin folders cleanup completed Dec 06 15:33:37 usbridge volumio[19566]: info: ------------------------------------------- Dec 06 15:33:37 usbridge volumio[19566]: info: ----- Core plugins startup ---- Dec 06 15:33:37 usbridge volumio[19566]: info: ------------------------------------------- Dec 06 15:33:37 usbridge volumio[19566]: info: Loading plugins from folder /volumio/app/plugins/ Dec 06 15:33:37 usbridge volumio[19566]: info: Adding plugin upnp to MyMusic Plugins Dec 06 15:33:37 usbridge volumio[19566]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 06 15:33:37 usbridge volumio[19566]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 06 15:33:37 usbridge volumio[19566]: info: Loading plugins from folder /data/plugins/ Dec 06 15:33:37 usbridge volumio[19566]: info: Loading plugin "system"... Dec 06 15:33:37 usbridge volumio[19566]: info: Loading plugin "appearance"... Dec 06 15:33:38 usbridge volumio[19566]: info: Loading plugin "network"... Dec 06 15:33:38 usbridge volumio[19566]: info: Refreshing Cached IP Addresses Dec 06 15:33:38 usbridge sudo[19595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 15:33:38 usbridge sudo[19595]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:38 usbridge sudo[19595]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:38 usbridge sudo[19597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 15:33:38 usbridge sudo[19597]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:38 usbridge volumio[19566]: info: Loading plugin "services"... Dec 06 15:33:38 usbridge sudo[19597]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:38 usbridge volumio[19566]: info: Loading plugin "alsa_controller"... Dec 06 15:33:38 usbridge sudo[19608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 06 15:33:38 usbridge sudo[19608]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:38 usbridge sudo[19608]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:38 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 15:33:38 usbridge volumio[19566]: info: Loading plugin "wizard"... Dec 06 15:33:38 usbridge volumio[19566]: info: Loading plugin "networkfs"... Dec 06 15:33:38 usbridge volumio[19566]: info: Cannot mount NAS Musik at system boot, trial number 1 ,retrying in 5 seconds Dec 06 15:33:38 usbridge volumio[19566]: info: Starting Udev Watcher for removable devices Dec 06 15:33:39 usbridge volumio[19566]: info: Ignoring mount for partition: boot Dec 06 15:33:39 usbridge volumio[19566]: info: Ignoring mount for partition: volumio Dec 06 15:33:39 usbridge volumio[19566]: info: Ignoring mount for partition: volumio_data Dec 06 15:33:39 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 15:33:39 usbridge volumio[19566]: info: Loading plugin "volumio_command_line_client"... Dec 06 15:33:39 usbridge volumio[19566]: info: Loading plugin "upnp"... Dec 06 15:33:39 usbridge volumio[19566]: info: [1765031619044] Starting Upmpd Daemon Dec 06 15:33:39 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 15:33:39 usbridge volumio[19566]: info: Loading plugin "my_music"... Dec 06 15:33:39 usbridge volumio[19566]: info: Loading plugin "mpd"... Dec 06 15:33:39 usbridge volumio-remote-updater[506]: [2025-12-06 15:33:39] [connect] Successful connection Dec 06 15:33:39 usbridge volumio[19566]: info: Loading plugin "upnp_browser"... Dec 06 15:33:39 usbridge volumio[19566]: info: Loading plugin "alarm-clock"... Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "airplay_emulation"... Dec 06 15:33:40 usbridge volumio[19566]: info: Starting Shairport Sync Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "last_100"... Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "webradio"... Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "i2s_dacs"... Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "volumiodiscovery"... Dec 06 15:33:40 usbridge volumio[19566]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 06 15:33:40 usbridge volumio[19566]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 15:33:40 usbridge volumio[19566]: *** WARNING *** For more information see Dec 06 15:33:40 usbridge volumio[19566]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 06 15:33:40 usbridge volumio[19566]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 15:33:40 usbridge node[19566]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 06 15:33:40 usbridge volumio[19566]: *** WARNING *** For more information see Dec 06 15:33:40 usbridge node[19566]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 15:33:40 usbridge node[19566]: *** WARNING *** For more information see Dec 06 15:33:40 usbridge node[19566]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 06 15:33:40 usbridge node[19566]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 06 15:33:40 usbridge node[19566]: *** WARNING *** For more information see Dec 06 15:33:40 usbridge volumio[19566]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 06 15:33:40 usbridge volumio[19566]: info: Discovery: Started advertising with name: usbridge Dec 06 15:33:40 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "outputs"... Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "albumart"... Dec 06 15:33:40 usbridge volumio[19566]: info: Plugin example_plugin is not enabled Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "inputs"... Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "updater_comm"... Dec 06 15:33:40 usbridge volumio[19566]: info: Plugin mpdemulation is not enabled Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "rest_api"... Dec 06 15:33:40 usbridge volumio[19566]: info: Loading plugin "websocket"... Dec 06 15:33:40 usbridge volumio[19566]: info: Starting Socket.io Server version 2.3.0 Dec 06 15:33:40 usbridge volumio[19566]: info: Loading i18n strings for locale fr Dec 06 15:33:40 usbridge volumio[19566]: Updating browse sources language Dec 06 15:33:40 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 15:33:40 usbridge volumio[19566]: Forking 3 albumart workers Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::initPlayerControls Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: Express server listening on port 3000 Dec 06 15:33:41 usbridge volumio[19566]: [Metrics] WebUI: 5s 511.71ms Dec 06 15:33:41 usbridge volumio[19566]: info: CoreStateMachine::resetVolumioState Dec 06 15:33:41 usbridge volumio[19566]: info: CoreStateMachine::getcurrentVolume Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::volumioRetrievevolume Dec 06 15:33:41 usbridge volumio[19566]: info: CoreStateMachine::pushState Dec 06 15:33:41 usbridge volumio[19566]: info: CorePlayQueue::getTrack 0 Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:41 usbridge volumio[19566]: info: Volumio Network Manager: Network status updated: 1 Dec 06 15:33:41 usbridge volumio[19566]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 06 15:33:41 usbridge volumio[19566]: wlan0 Interface doesn't support scanning. Dec 06 15:33:41 usbridge volumio[19566]: info: Cannot use regular scanning, forcing with ap-force Dec 06 15:33:41 usbridge sudo[19659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 06 15:33:41 usbridge sudo[19659]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:41 usbridge sudo[19659]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:41 usbridge volumio[19566]: command failed: No such device (-19) Dec 06 15:33:41 usbridge volumio[19566]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 06 15:33:41 usbridge volumio[19566]: command failed: No such device (-19) Dec 06 15:33:41 usbridge volumio[19566]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.169 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Dec 06 15:33:41 usbridge volumio[19566]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.169 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Dec 06 15:33:41 usbridge volumio-remote-updater[506]: [2025-12-06 15:33:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1765031619 101 Dec 06 15:33:41 usbridge volumio[19566]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Dec 06 15:33:41 usbridge volumio[19566]: info: Reloading queue from file Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: info: Setting Device type: Raspberry PI Dec 06 15:33:41 usbridge volumio[19566]: info: CoreStateMachine::setRepeat null single undefined Dec 06 15:33:41 usbridge volumio[19566]: info: CoreStateMachine::pushState Dec 06 15:33:41 usbridge volumio[19566]: info: CorePlayQueue::getTrack 0 Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:41 usbridge volumio[19566]: info: CoreStateMachine::setRandom null Dec 06 15:33:41 usbridge volumio[19566]: info: CoreStateMachine::pushState Dec 06 15:33:41 usbridge volumio[19566]: info: CorePlayQueue::getTrack 0 Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::volumioPushState Dec 06 15:33:41 usbridge volumio[19566]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 06 15:33:41 usbridge volumio[19566]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a02100 Dec 06 15:33:41 usbridge volumio[19566]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 06 15:33:41 usbridge volumio[19566]: info: Completed loading Core Plugins Dec 06 15:33:41 usbridge volumio[19566]: info: Preparing to generate the ALSA configuration file Dec 06 15:33:41 usbridge volumio[19566]: info: Asound.conf file unchanged, so no further update is needed Dec 06 15:33:41 usbridge volumio[19566]: info: Output device has changed, restarting MPD Dec 06 15:33:41 usbridge volumio[19566]: info: Output device has changed, restarting Shairport Sync Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:41 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 15:33:41 usbridge sudo[19669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 15:33:41 usbridge sudo[19669]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:41 usbridge sudo[19671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 15:33:41 usbridge sudo[19669]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:41 usbridge sudo[19671]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:41 usbridge systemd[1]: Stopping Music Player Daemon... Dec 06 15:33:41 usbridge volumio[19566]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 15:33:41 usbridge volumio[19566]: info: ___________ START PLUGINS ___________ Dec 06 15:33:42 usbridge volumio[19566]: info: ControllerMpd::onStart: Initializing MPD Dec 06 15:33:42 usbridge volumio[19566]: info: Creating MPD Configuration file Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 06 15:33:42 usbridge sudo[19678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 15:33:42 usbridge sudo[19678]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:42 usbridge volumio[19566]: info: [1765031622093] CoreMusicLibrary::Adding element Serveurs Média Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 15:33:42 usbridge sudo[19678]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:42 usbridge sudo[19680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 15:33:42 usbridge sudo[19680]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:42 usbridge volumio[19566]: Starting albumart workers Dec 06 15:33:42 usbridge volumio[19566]: Starting albumart workers Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 15:33:42 usbridge systemd[1]: mpd.service: Succeeded. Dec 06 15:33:42 usbridge systemd[1]: Stopped Music Player Daemon. Dec 06 15:33:42 usbridge volumio[19566]: Starting albumart workers Dec 06 15:33:42 usbridge volumio[19566]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 15:33:42 usbridge systemd[1]: Starting Music Player Daemon... Dec 06 15:33:42 usbridge volumio[19566]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 15:33:42 usbridge volumio[19566]: info: [1765031622316] CoreMusicLibrary::Adding element Last_100 Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 15:33:42 usbridge volumio[19566]: info: [1765031622320] CoreMusicLibrary::Adding element Webradio Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 15:33:42 usbridge volumio[19566]: info: Initializing BBC Radios Dec 06 15:33:42 usbridge sudo[19686]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 15:33:42 usbridge sudo[19686]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:42 usbridge sudo[19686]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 15:33:42 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 15:33:42 usbridge volumio[19566]: info: Volumio Calling Home Dec 06 15:33:43 usbridge volumio[19566]: info: MPD Permissions set Dec 06 15:33:43 usbridge volumio[19566]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 15:33:43 usbridge volumio[19566]: info: Discovery: adding 51b104bf-36af-47d4-8ebc-be9b26aab7f5 Dec 06 15:33:43 usbridge volumio[19566]: info: Discovery: Found device usbridge Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::volumioGetState Dec 06 15:33:43 usbridge volumio[19566]: info: CorePlayQueue::getTrack 0 Dec 06 15:33:43 usbridge volumio[19566]: info: Volumio called home Dec 06 15:33:43 usbridge volumio[19566]: info: Starting Shairport Sync Dec 06 15:33:43 usbridge volumio[19566]: info: Starting Shairport Sync Dec 06 15:33:43 usbridge volumio[19566]: info: Starting Shairport Sync Dec 06 15:33:43 usbridge sudo[19740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 15:33:43 usbridge sudo[19740]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:43 usbridge sudo[19742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 15:33:43 usbridge sudo[19742]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:43 usbridge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 06 15:33:43 usbridge shairport-sync[1073]: {"time":1765031594394,"response":"stopAirplayPlayback Success"} Dec 06 15:33:43 usbridge systemd[1]: shairport-sync.service: Succeeded. Dec 06 15:33:43 usbridge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 06 15:33:43 usbridge volumio[19566]: info: CoreCommandRouter::volumioGetState Dec 06 15:33:43 usbridge volumio[19566]: info: CorePlayQueue::getTrack 0 Dec 06 15:33:43 usbridge sudo[19745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 15:33:43 usbridge sudo[19745]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 06 15:33:43 usbridge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 06 15:33:43 usbridge sudo[19740]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:43 usbridge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 06 15:33:43 usbridge volumio[19566]: info: Shairport-Sync Started Dec 06 15:33:43 usbridge systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 06 15:33:43 usbridge systemd[1]: shairport-sync.service: Succeeded. Dec 06 15:33:43 usbridge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 06 15:33:43 usbridge volumio[19566]: Error adding Membership: Error: addMembership EINVAL Dec 06 15:33:43 usbridge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 06 15:33:43 usbridge sudo[19742]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:43 usbridge sudo[19745]: pam_unix(sudo:session): session closed for user root Dec 06 15:33:43 usbridge volumio[19566]: info: Shairport-Sync Started Dec 06 15:33:43 usbridge volumio[19566]: info: Shairport-Sync Started Dec 06 15:33:44 usbridge volumio[19566]: info: Cannot mount NAS Musik at system boot, trial number 2 ,retrying in 5 seconds Dec 06 15:33:44 usbridge volumio[19566]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.169 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Dec 06 15:33:44 usbridge volumio[19566]: Searching plugin music_service/mpd Dec 06 15:33:44 usbridge volumio[19566]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 06 15:33:44 usbridge volumio[19566]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 15:33:44 usbridge volumio[19566]: TypeError: Cannot read property 'then' of undefined Dec 06 15:33:44 usbridge volumio[19566]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1583:17) Dec 06 15:33:44 usbridge volumio[19566]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 06 15:33:44 usbridge volumio[19566]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:477:37) Dec 06 15:33:44 usbridge volumio[19566]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Dec 06 15:33:44 usbridge volumio[19566]: at Socket.emit (events.js:315:20) Dec 06 15:33:44 usbridge volumio[19566]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 06 15:33:44 usbridge volumio[19566]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 06 15:33:44 usbridge volumio[19566]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 15:33:44 usbridge mpd[19699]: Dec 06 15:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 15:33:44 usbridge sudo[19762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 15:32 Dec 06 15:33:44 usbridge sudo[19762]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"