Jan 11 17:52:00 volumio-aurel volumio[9663]: verbose: New Socket.io Connection to 192.168.1.63 from 192.168.1.209 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Pro Build/BP3A.251005.004.B2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 11 17:52:00 volumio-aurel sudo[10993]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 17:52:00 volumio-aurel sudo[10993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 17:52:00 volumio-aurel sudo[10993]: pam_unix(sudo:session): session closed for user root Jan 11 17:52:00 volumio-aurel sudo[10995]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 17:52:00 volumio-aurel sudo[10995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 17:52:00 volumio-aurel sudo[10995]: pam_unix(sudo:session): session closed for user root Jan 11 17:52:00 volumio-aurel volumio[9663]: verbose: New Socket.io Connection to 192.168.1.63 from 192.168.1.209 UA: Mozilla/5.0 (Linux; Android 16; Pixel 7 Pro Build/BP3A.251005.004.B2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 11 17:52:00 volumio-aurel volumio[9663]: info: Executing endpoint metavolumio Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 17:52:00 volumio-aurel volumio[9663]: info: Executing endpoint metavolumio Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetQueue Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreStateMachine::getQueue Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CorePlayQueue::getQueue Jan 11 17:52:00 volumio-aurel volumio[9663]: info: Listing playlists Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 17:52:00 volumio-aurel volumio[9663]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 11 17:52:00 volumio-aurel volumio[9663]: info: Received Get System Info Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 17:52:00 volumio-aurel volumio[9663]: info: Discovery: Getting this device information Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 17:52:00 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 17:52:02 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 11 17:52:02 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 17:52:02 volumio-aurel volumio[9663]: info: Received Get System Info Jan 11 17:52:02 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 17:52:02 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 17:52:02 volumio-aurel volumio[9663]: info: Discovery: Getting this device information Jan 11 17:52:02 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:02 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Received Get System Info Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Discovery: Getting this device information Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Preload queue cleared Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::ClearQueue Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::stop Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::serviceStop Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::serviceStop Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: [FUNC] stop Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::clearPlayQueue Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::saveQueue Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioPushQueue Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::addQueueItems Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::addQueueItems Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Preload queue cleared Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Adding Item to queue: artists://Morcheeba/Big%20calm Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Exploding uri artists://Morcheeba/Big%20calm in service mpd Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioPushQueue Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::saveQueue Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::updateTrackBlock Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrackBlock Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioPlay Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: UNSET VOLATILE: Service: bluetooth Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::play index 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 17:52:03 volumio-aurel volumio[9663]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::stop Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::resetVolumioState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::getcurrentVolume Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::play index undefined Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioStop Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::stop Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::startPlaybackTimer Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/Morcheeba/Big calm/01 The sea.flac Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand stop took 8 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Jan 11 17:52:03 volumio-aurel volumio[9663]: ---------------------------- MPD announces system playlist update Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Ignoring MPD Status Update Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand clear took 5 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/Morcheeba/Big calm/01 The sea.flac" Jan 11 17:52:03 volumio-aurel volumio[9663]: error: updateQueue error: null Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Jan 11 17:52:03 volumio-aurel volumio[9663]: ---------------------------- MPD announces system playlist update Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Ignoring MPD Status Update Jan 11 17:52:03 volumio-aurel volumio[9663]: info: ------------------------------ 20ms Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand add "NAS/NAS/Morcheeba/Big calm/01 The sea.flac" took 17 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand play Jan 11 17:52:03 volumio-aurel volumio[9663]: info: ------------------------------ 8ms Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand play took 3 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: info: VolumeController:: Volume=100 Mute =true Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::pushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioPushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output update for this device Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::updateTrackBlock Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrackBlock Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Jan 11 17:52:03 volumio-aurel volumio[9663]: ---------------------------- MPD announces state update: player Jan 11 17:52:03 volumio-aurel volumio[9663]: info: ControllerMpd::getState Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Jan 11 17:52:03 volumio-aurel volumio[9663]: ---------------------------- MPD announces state update: player Jan 11 17:52:03 volumio-aurel volumio[9663]: info: ControllerMpd::getState Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand status took 16 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::parseState Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand status took 12 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::parseState Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:52:03 volumio-aurel volumio[9663]: info: ControllerMpd::pushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::servicePushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"643 Kbps","isStreaming":false,"title":"The sea","artist":"Morcheeba","album":"Big calm","uri":"NAS/NAS/Morcheeba/Big calm/01 The sea.flac","trackType":"flac"} Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: CURRENT POSITION 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::syncState stateService play Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::syncState currentStatus stop Jan 11 17:52:03 volumio-aurel volumio[9663]: info: ------------------------------ 42ms Jan 11 17:52:03 volumio-aurel volumio[9663]: info: VolumeController:: Volume=100 Mute =false Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::pushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioPushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output update for this device Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:52:03 volumio-aurel volumio[9663]: info: ControllerMpd::pushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::servicePushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"643 Kbps","isStreaming":false,"title":"The sea","artist":"Morcheeba","album":"Big calm","uri":"NAS/NAS/Morcheeba/Big calm/01 The sea.flac","trackType":"flac"} Jan 11 17:52:03 volumio-aurel volumio[9663]: verbose: CURRENT POSITION 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::syncState stateService play Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::syncState currentStatus play Jan 11 17:52:03 volumio-aurel volumio[9663]: info: Received an update from plugin. extracting info from payload Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::pushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioPushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output update for this device Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreStateMachine::pushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioPushState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output update for this device Jan 11 17:52:03 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:03 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:04 volumio-aurel volumio[9663]: info: ------------------------------ 168ms Jan 11 17:52:04 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:04 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:04 volumio-aurel volumio[9663]: info: Listing playlists Jan 11 17:52:04 volumio-aurel volumio[9663]: info: Listing playlists Jan 11 17:52:10 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 17:52:11 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Setting this device as multiroomSync server Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Setting another device as multiroomSync client Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Setting this device as Server Jan 11 17:52:11 volumio-aurel volumio[9663]: info: Jan 11 17:52:11 volumio-aurel volumio[9663]: [1768150331321] ---------------------------- MRS: Setting Multiroom Server Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Pushing multiroomSync output Jan 11 17:52:11 volumio-aurel volumio[9663]: info: Enabled audio output: a1533885-3456-4e80-80dc-8263b74cae2a Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: STARTING SNAPCLIENT Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Updating multiroomSync output Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: STOPPING SNAPCLIENT Jan 11 17:52:11 volumio-aurel sudo[11036]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapclient Jan 11 17:52:11 volumio-aurel sudo[11036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Updating multiroomSync output Jan 11 17:52:11 volumio-aurel systemd[1]: Started volumioSnapclient.service - Snapcast client. Jan 11 17:52:11 volumio-aurel sudo[11036]: pam_unix(sudo:session): session closed for user root Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: SNAPCLIENT STARTED Jan 11 17:52:11 volumio-aurel snapclient[11038]: 2026-01-11 17-52-11.606 [Info] (Snapclient) Version 0.26.0 Jan 11 17:52:11 volumio-aurel snapclient[11038]: 2026-01-11 17-52-11.627 [Info] (Avahi) (Browser) CACHE_EXHAUSTED Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: STARTING SNAPSERVER Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: enable multiroom server output Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Set multiroom target PCM to volumioMultiRoom Jan 11 17:52:11 volumio-aurel volumio[9663]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: Snap server restart Jan 11 17:52:11 volumio-aurel volumio[9663]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Jan 11 17:52:11 volumio-aurel sudo[11043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapserver Jan 11 17:52:11 volumio-aurel sudo[11043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 17:52:11 volumio-aurel systemd[1]: Started volumioSnapserver.service - Snapcast server. Jan 11 17:52:11 volumio-aurel sudo[11043]: pam_unix(sudo:session): session closed for user root Jan 11 17:52:11 volumio-aurel volumio[9663]: info: MRS: SNAPSERVER STARTED Jan 11 17:52:11 volumio-aurel snapserver[11045]: Version 0.26.0 Jan 11 17:52:11 volumio-aurel snapserver[11045]: Adding source: pipe:///tmp/multiroom/server/fifo?name=Radio&sampleformat=48000:16:2&codec=flac Jan 11 17:52:11 volumio-aurel snapserver[11045]: Settings file: "/var/lib/snapserver/.config/snapserver/server.json" Jan 11 17:52:11 volumio-aurel snapserver[11045]: Using HTTP host name: volumio-aurel Jan 11 17:52:11 volumio-aurel snapserver[11045]: Adding service 'Snapcast' Jan 11 17:52:11 volumio-aurel snapserver[11045]: PcmStream: Radio, sampleFormat: 48000:16:2 Jan 11 17:52:11 volumio-aurel snapserver[11045]: PipeStream mode: create Jan 11 17:52:11 volumio-aurel snapserver[11045]: Stream: {"fragment":"","host":"","path":"/tmp/multiroom/server/fifo","query":{"chunk_ms":"50","codec":"flac","name":"Radio","sampleformat":"48000:16:2"},"raw":"pipe:////tmp/multiroom/server/fifo?chunk_ms=50&codec=flac&name=Radio&sampleformat=48000:16:2","scheme":"pipe"} Jan 11 17:52:11 volumio-aurel snapserver[11045]: Init - compression level: 2 Jan 11 17:52:11 volumio-aurel snapserver[11045]: Creating TCP acceptor for address: 0.0.0.0, port: 1705 Jan 11 17:52:11 volumio-aurel snapserver[11045]: Creating HTTP acceptor for address: 0.0.0.0, port: 1780 Jan 11 17:52:11 volumio-aurel snapserver[11045]: Creating stream acceptor for address: 0.0.0.0, port: 1704 Jan 11 17:52:11 volumio-aurel snapserver[11045]: Number of threads: 4, hw threads: 4 Jan 11 17:52:12 volumio-aurel snapserver[11045]: State changed: Radio, state: idle => playing Jan 11 17:52:12 volumio-aurel snapserver[11045]: onStateChanged (Radio): playing Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.682 [Info] (Avahi) (Browser) CACHE_EXHAUSTED Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.843 [Info] (Avahi) (Browser) NEW: service 'Snapcast' of type '_snapcast._tcp' in domain 'local' Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.846 [Info] (Avahi) (Browser) NEW: service 'Snapcast' of type '_snapcast._tcp' in domain 'local' Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.880 [Info] (Avahi) Service 'Snapcast' of type '_snapcast._tcp' in domain 'local': Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.880 [Info] (Avahi) volumio-aurel.local:1704 (127.0.0.1) Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.884 [Info] (Controller) Found server 127.0.0.1:1704 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.885 [Info] (Connection) Resolving host IP for: 127.0.0.1 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.885 [Info] (Connection) Connecting Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.885 [Notice] (Connection) Connected to 127.0.0.1 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.885 [Info] (Connection) My MAC: "b8:27:eb:c0:4d:dd", socket: 8 Jan 11 17:52:12 volumio-aurel snapserver[11045]: StreamServer::NewConnection: 127.0.0.1 Jan 11 17:52:12 volumio-aurel snapserver[11045]: Service 'Snapcast' successfully established. Jan 11 17:52:12 volumio-aurel snapserver[11045]: Hello from b8:27:eb:c0:4d:dd, host: volumio-aurel, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.940 [Info] (Controller) ServerSettings - buffer: 1500, latency: 0, volume: 100, muted: 0 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.940 [Info] (Controller) Codec: flac, sampleformat: 48000:16:2 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.940 [Info] (Player) Player name: alsa, device: volumioMultiRoomClient, description: , idx: 14, sharing mode: unspecified, parameters: Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.940 [Info] (Player) Mixer mode: software, parameters: Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.940 [Info] (Player) Sampleformat: 48000:16:2, stream: 48000:16:2 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.940 [Info] (Alsa) Using default buffer_time: 80 ms, default fragments: 4 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.944 [Info] (Alsa) PCM name: volumioMultiRoomClient, sample rate: 48000 Hz, channels: 2, buffer time: 80000 us, periods: 4, period time: 20000 us, period frames: 960 Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.945 [Info] (Stream) No chunks available Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.945 [Info] (Alsa) Failed to get chunk Jan 11 17:52:12 volumio-aurel snapclient[11038]: 2026-01-11 17-52-12.968 [Info] (Controller) diff to server [ms]: -0.006 Jan 11 17:52:13 volumio-aurel volumio[9663]: info: MRS: Updating multiroomSync output Jan 11 17:52:14 volumio-aurel snapserver[11045]: StreamServer::NewConnection: 192.168.1.61 Jan 11 17:52:14 volumio-aurel snapserver[11045]: Hello from b8:27:eb:b2:49:8e, host: volumio-wz, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Jan 11 17:52:14 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:14 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 11 17:52:18 volumio-aurel volumio[9663]: info: Received Get System Version Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 17:52:18 volumio-aurel volumio[9663]: info: Received Get System Info Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 17:52:18 volumio-aurel volumio[9663]: info: Discovery: Getting this device information Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:18 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 17:52:24 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:24 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:24 volumio-aurel volumio[9663]: info: Listing playlists Jan 11 17:52:24 volumio-aurel volumio[9663]: info: Listing playlists Jan 11 17:52:34 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:34 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:37 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 11 17:52:37 volumio-aurel volumio[9663]: info: Preload queue cleared Jan 11 17:52:39 volumio-aurel volumio[9663]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 11 17:52:39 volumio-aurel volumio[9663]: info: Preload queue cleared Jan 11 17:52:44 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:44 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:44 volumio-aurel volumio[9663]: info: Listing playlists Jan 11 17:52:44 volumio-aurel volumio[9663]: info: Listing playlists Jan 11 17:52:54 volumio-aurel volumio[9663]: info: CoreCommandRouter::volumioGetState Jan 11 17:52:54 volumio-aurel volumio[9663]: info: CorePlayQueue::getTrack 0 Jan 11 17:52:59 volumio-aurel volumio[9663]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 17:52:59 volumio-aurel volumio[9663]: TypeError: Cannot read properties of null (reading 'slice') Jan 11 17:52:59 volumio-aurel volumio[9663]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 11 17:52:59 volumio-aurel volumio[9663]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 11 17:52:59 volumio-aurel volumio[9663]: at Object.onceWrapper (node:events:629:26) Jan 11 17:52:59 volumio-aurel volumio[9663]: at ClientRequest.emit (node:events:514:28) Jan 11 17:52:59 volumio-aurel volumio[9663]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Jan 11 17:52:59 volumio-aurel volumio[9663]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Jan 11 17:52:59 volumio-aurel volumio[9663]: at TLSSocket.socketOnData (node:_http_client:535:22) Jan 11 17:52:59 volumio-aurel volumio[9663]: at TLSSocket.emit (node:events:514:28) Jan 11 17:52:59 volumio-aurel volumio[9663]: at addChunk (node:internal/streams/readable:343:12) Jan 11 17:52:59 volumio-aurel volumio[9663]: at readableAddChunk (node:internal/streams/readable:316:9) Jan 11 17:52:59 volumio-aurel volumio[9663]: at Readable.push (node:internal/streams/readable:253:10) Jan 11 17:52:59 volumio-aurel volumio[9663]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Jan 11 17:52:59 volumio-aurel volumio[9663]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 17:53:00 volumio-aurel sudo[11192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 17:52' Jan 11 17:53:00 volumio-aurel sudo[11192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ac7dd5922ede329d1459d48b226ddc71f5209a2c" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="3441cddd43405c36fe444484553f10f2f5bc830b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Nov 13 11:04:27 UTC 2025" VOLUMIO_VERSION="4.069" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="7da8d6d9f8baf8621af4ec2af5a00f00"