Oct 27 07:28:04 volumio-main systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Oct 27 07:28:04 volumio-main dbus-daemon[677]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.5760' (uid=0 pid=25957 comm="timedatectl show --property=NTPSynchronized --valu") Oct 27 07:28:04 volumio-main systemd[1]: Starting systemd-timedated.service - Time & Date Service... Oct 27 07:28:05 volumio-main dbus-daemon[677]: [system] Successfully activated service 'org.freedesktop.timedate1' Oct 27 07:28:05 volumio-main systemd[1]: Started systemd-timedated.service - Time & Date Service. Oct 27 07:28:05 volumio-main setdatetime-helper.sh[25956]: Time is already synchronized. Oct 27 07:28:05 volumio-main systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Oct 27 07:28:05 volumio-main systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Oct 27 07:28:06 volumio-main sudo[25962]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 07:28:06 volumio-main sudo[25962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:28:06 volumio-main sudo[25962]: pam_unix(sudo:session): session closed for user root Oct 27 07:28:06 volumio-main sudo[25964]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 07:28:06 volumio-main sudo[25964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:28:06 volumio-main sudo[25964]: pam_unix(sudo:session): session closed for user root Oct 27 07:28:06 volumio-main volumio[1367]: verbose: New Socket.io Connection to 192.168.103.200 from 192.168.102.109 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 27 07:28:06 volumio-main sudo[25968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 07:28:06 volumio-main sudo[25968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:28:06 volumio-main sudo[25968]: pam_unix(sudo:session): session closed for user root Oct 27 07:28:06 volumio-main sudo[25970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 07:28:06 volumio-main sudo[25970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:28:06 volumio-main sudo[25970]: pam_unix(sudo:session): session closed for user root Oct 27 07:28:06 volumio-main volumio[1367]: verbose: New Socket.io Connection to 192.168.103.200 from 192.168.102.109 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 07:28:06 volumio-main volumio[1367]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 07:28:06 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:28:06 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:06 volumio-main volumio[1367]: info: Listing playlists Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 07:28:06 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 07:28:07 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 27 07:28:07 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:08 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 27 07:28:08 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 07:28:08 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:28:08 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:28:08 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:28:08 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:28:08 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:08 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:28:09 volumio-main volumio[1367]: info: CoreCommandRouter::volumioPlay Oct 27 07:28:09 volumio-main volumio[1367]: info: CoreStateMachine::play index undefined Oct 27 07:28:09 volumio-main volumio[1367]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 27 07:28:09 volumio-main volumio[1367]: info: CorePlayQueue::getTrack 0 Oct 27 07:28:09 volumio-main volumio[1367]: info: CoreStateMachine::startPlaybackTimer Oct 27 07:28:09 volumio-main volumio[1367]: info: CorePlayQueue::getTrack 0 Oct 27 07:28:09 volumio-main volumio[1367]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2166208044 Oct 27 07:28:09 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:09 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:09 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:09 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:09 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:09 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:10 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 07:28:10 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:28:10 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:28:10 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:28:10 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:28:10 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:10 volumio-main volumio[1367]: info: CorePlayQueue::getTrack 0 Oct 27 07:28:10 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:28:10 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand stop Oct 27 07:28:10 volumio-main volumio[1367]: info: sendMpdCommand stop took 0 milliseconds Oct 27 07:28:10 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand clear Oct 27 07:28:10 volumio-main volumio[1367]: info: Oct 27 07:28:10 volumio-main volumio[1367]: ---------------------------- MPD announces system playlist update Oct 27 07:28:10 volumio-main volumio[1367]: info: Ignoring MPD Status Update Oct 27 07:28:10 volumio-main volumio[1367]: info: sendMpdCommand clear took 0 milliseconds Oct 27 07:28:10 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-media.sndcdn.com/r3Lr1lA9I9rE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vcjNMcjFsQTlJOXJFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzYxNTE4MDg0fX19XX0_&Signature=DQENNmcj1Avl1zGYH0kjLPuBb1Rj0l7Q2j8AbdFzFM31f7it0xb2mYTFS7HB5ZzdgmfDtOyT2jeS~r7fV2t28zh2JF8l1KjhEaUdrZQRHjuGTO-JXK~xxJa0ETE8nUETKvBUvA7IGybxUuvMkfrakeRMjRK-nmldPR~Mj8WhjTP737lKZYRE4jRxg6tcBnJzOQNK3c-8tGpo60FuDCq123BlfDiBQwjoWb~IFir12FAXIKTJQp0imzA98xCpX2dyWb~-7xraIu1UOVyPTk2kR5oNkPFKMoqiQ2Mttk21u9vxoBHuFfViuo4vKopI3tCqYxS~CtznFfAIWN0wt8MK2A__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" Oct 27 07:28:10 volumio-main volumio[1367]: error: updateQueue error: null Oct 27 07:28:10 volumio-main volumio[1367]: info: ------------------------------ 1ms Oct 27 07:28:10 volumio-main volumio[1367]: info: Oct 27 07:28:10 volumio-main volumio[1367]: ---------------------------- MPD announces system playlist update Oct 27 07:28:10 volumio-main volumio[1367]: info: Ignoring MPD Status Update Oct 27 07:28:10 volumio-main volumio[1367]: info: sendMpdCommand addid "https://cf-media.sndcdn.com/r3Lr1lA9I9rE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vcjNMcjFsQTlJOXJFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzYxNTE4MDg0fX19XX0_&Signature=DQENNmcj1Avl1zGYH0kjLPuBb1Rj0l7Q2j8AbdFzFM31f7it0xb2mYTFS7HB5ZzdgmfDtOyT2jeS~r7fV2t28zh2JF8l1KjhEaUdrZQRHjuGTO-JXK~xxJa0ETE8nUETKvBUvA7IGybxUuvMkfrakeRMjRK-nmldPR~Mj8WhjTP737lKZYRE4jRxg6tcBnJzOQNK3c-8tGpo60FuDCq123BlfDiBQwjoWb~IFir12FAXIKTJQp0imzA98xCpX2dyWb~-7xraIu1UOVyPTk2kR5oNkPFKMoqiQ2Mttk21u9vxoBHuFfViuo4vKopI3tCqYxS~CtznFfAIWN0wt8MK2A__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3" took 1 milliseconds Oct 27 07:28:10 volumio-main volumio[1367]: verbose: MPD COMMAND [object Object] Oct 27 07:28:10 volumio-main volumio[1367]: verbose: MPD COMMAND [object Object] Oct 27 07:28:10 volumio-main volumio[1367]: verbose: MPD COMMAND [object Object] Oct 27 07:28:10 volumio-main volumio[1367]: info: Oct 27 07:28:10 volumio-main volumio[1367]: ---------------------------- MPD announces system playlist update Oct 27 07:28:10 volumio-main volumio[1367]: info: Ignoring MPD Status Update Oct 27 07:28:10 volumio-main volumio[1367]: info: ------------------------------ 1ms Oct 27 07:28:10 volumio-main volumio[1367]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 27 07:28:10 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand play Oct 27 07:28:10 volumio-main volumio[1367]: info: ------------------------------ 1ms Oct 27 07:28:10 volumio-main volumio[1367]: info: sendMpdCommand play took 1 milliseconds Oct 27 07:28:11 volumio-main volumio[1367]: info: Oct 27 07:28:11 volumio-main volumio[1367]: ---------------------------- MPD announces state update: player Oct 27 07:28:11 volumio-main volumio[1367]: info: ControllerMpd::getState Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:28:11 volumio-main volumio[1367]: info: Oct 27 07:28:11 volumio-main volumio[1367]: ---------------------------- MPD announces state update: player Oct 27 07:28:11 volumio-main volumio[1367]: info: sendMpdCommand status took 3 milliseconds Oct 27 07:28:11 volumio-main volumio[1367]: info: ControllerMpd::getState Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand status Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::parseState Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 07:28:11 volumio-main volumio[1367]: info: sendMpdCommand status took 1 milliseconds Oct 27 07:28:11 volumio-main volumio[1367]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::parseState Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::parseTrackInfo Oct 27 07:28:11 volumio-main volumio[1367]: info: ControllerMpd::pushState Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreCommandRouter::servicePushState Oct 27 07:28:11 volumio-main volumio[1367]: info: CorePlayQueue::getTrack 0 Oct 27 07:28:11 volumio-main volumio[1367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ENZO PIANO - VEGA (Melancholic Piano for Reflection)","artist":"ONERI PIANO","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/r3Lr1lA9I9rE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vcjNMcjFsQTlJOXJFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzYxNTE4MDg0fX19XX0_&Signature=DQENNmcj1Avl1zGYH0kjLPuBb1Rj0l7Q2j8AbdFzFM31f7it0xb2mYTFS7HB5ZzdgmfDtOyT2jeS~r7fV2t28zh2JF8l1KjhEaUdrZQRHjuGTO-JXK~xxJa0ETE8nUETKvBUvA7IGybxUuvMkfrakeRMjRK-nmldPR~Mj8WhjTP737lKZYRE4jRxg6tcBnJzOQNK3c-8tGpo60FuDCq123BlfDiBQwjoWb~IFir12FAXIKTJQp0imzA98xCpX2dyWb~-7xraIu1UOVyPTk2kR5oNkPFKMoqiQ2Mttk21u9vxoBHuFfViuo4vKopI3tCqYxS~CtznFfAIWN0wt8MK2A__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 27 07:28:11 volumio-main volumio[1367]: verbose: CURRENT POSITION 0 Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreStateMachine::syncState stateService play Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreStateMachine::syncState currentStatus stop Oct 27 07:28:11 volumio-main volumio[1367]: info: ------------------------------ 7ms Oct 27 07:28:11 volumio-main volumio[1367]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 27 07:28:11 volumio-main volumio[1367]: verbose: ControllerMpd::parseTrackInfo Oct 27 07:28:11 volumio-main volumio[1367]: info: ControllerMpd::pushState Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreCommandRouter::servicePushState Oct 27 07:28:11 volumio-main volumio[1367]: info: CorePlayQueue::getTrack 0 Oct 27 07:28:11 volumio-main volumio[1367]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ENZO PIANO - VEGA (Melancholic Piano for Reflection)","artist":"ONERI PIANO","album":"SoundCloud Track","uri":"https://cf-media.sndcdn.com/r3Lr1lA9I9rE.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20vcjNMcjFsQTlJOXJFLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzYxNTE4MDg0fX19XX0_&Signature=DQENNmcj1Avl1zGYH0kjLPuBb1Rj0l7Q2j8AbdFzFM31f7it0xb2mYTFS7HB5ZzdgmfDtOyT2jeS~r7fV2t28zh2JF8l1KjhEaUdrZQRHjuGTO-JXK~xxJa0ETE8nUETKvBUvA7IGybxUuvMkfrakeRMjRK-nmldPR~Mj8WhjTP737lKZYRE4jRxg6tcBnJzOQNK3c-8tGpo60FuDCq123BlfDiBQwjoWb~IFir12FAXIKTJQp0imzA98xCpX2dyWb~-7xraIu1UOVyPTk2kR5oNkPFKMoqiQ2Mttk21u9vxoBHuFfViuo4vKopI3tCqYxS~CtznFfAIWN0wt8MK2A__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.mp3","trackType":"mp3"} Oct 27 07:28:11 volumio-main volumio[1367]: verbose: CURRENT POSITION 0 Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreStateMachine::syncState stateService play Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreStateMachine::syncState currentStatus play Oct 27 07:28:11 volumio-main volumio[1367]: info: Received an update from plugin. extracting info from payload Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreStateMachine::pushState Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreCommandRouter::volumioPushState Oct 27 07:28:11 volumio-main volumio[1367]: info: MRS: Pushing multiroomSync output update for this device Oct 27 07:28:11 volumio-main volumio[1367]: info: MRS: Pushing multiroomSync output Oct 27 07:28:11 volumio-main volumio[1367]: error: Help! Some callbacks for volumioPushState are crashing! Oct 27 07:28:11 volumio-main volumio[1367]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreStateMachine::pushState Oct 27 07:28:11 volumio-main volumio[1367]: info: CoreCommandRouter::volumioPushState Oct 27 07:28:11 volumio-main volumio[1367]: info: MRS: Pushing multiroomSync output update for this device Oct 27 07:28:11 volumio-main volumio[1367]: info: MRS: Pushing multiroomSync output Oct 27 07:28:11 volumio-main volumio[1367]: error: Help! Some callbacks for volumioPushState are crashing! Oct 27 07:28:11 volumio-main volumio[1367]: error: Cannot read properties of undefined (reading 'sendVolumeMute') Oct 27 07:28:11 volumio-main volumio[1367]: info: ------------------------------ 24ms Oct 27 07:28:13 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:13 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:13 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:13 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:13 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:13 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 27 07:28:15 volumio-main volumio[1367]: info: MRS: Setting this device as multiroomSync server Oct 27 07:28:15 volumio-main volumio[1367]: info: MRS: Setting another device as multiroomSync client Oct 27 07:28:15 volumio-main volumio[1367]: info: MRS: Setting this device as Server Oct 27 07:28:15 volumio-main volumio[1367]: info: Oct 27 07:28:15 volumio-main volumio[1367]: [1761517695954] ---------------------------- MRS: Setting Multiroom Server Oct 27 07:28:15 volumio-main volumio[1367]: info: MRS: Pushing multiroomSync output Oct 27 07:28:15 volumio-main volumio[1367]: info: Enabled audio output: e2546957-9866-41a7-ade1-bca2b696aa5c Oct 27 07:28:15 volumio-main volumio[1367]: info: MRS: STARTING SNAPCLIENT Oct 27 07:28:15 volumio-main volumio[1367]: info: MRS: STOPPING SNAPCLIENT Oct 27 07:28:15 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:15 volumio-main sudo[25991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapclient Oct 27 07:28:15 volumio-main sudo[25991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:16 volumio-main systemd[1]: Started volumioSnapclient.service - Snapcast client. Oct 27 07:28:16 volumio-main sudo[25991]: pam_unix(sudo:session): session closed for user root Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: SNAPCLIENT STARTED Oct 27 07:28:16 volumio-main snapclient[25993]: 2025-10-27 07-28-16.168 [Info] (Snapclient) Version 0.26.0 Oct 27 07:28:16 volumio-main snapclient[25993]: 2025-10-27 07-28-16.185 [Info] (Avahi) (Browser) CACHE_EXHAUSTED Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: STARTING SNAPSERVER Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: enable multiroom server output Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Set multiroom target PCM to volumioMultiRoom Oct 27 07:28:16 volumio-main volumio[1367]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Snap server restart Oct 27 07:28:16 volumio-main volumio[1367]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Oct 27 07:28:16 volumio-main sudo[25997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapserver Oct 27 07:28:16 volumio-main sudo[25997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:28:16 volumio-main systemd[1]: Started volumioSnapserver.service - Snapcast server. Oct 27 07:28:16 volumio-main sudo[25997]: pam_unix(sudo:session): session closed for user root Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: SNAPSERVER STARTED Oct 27 07:28:16 volumio-main snapserver[25999]: Version 0.26.0 Oct 27 07:28:16 volumio-main snapserver[25999]: Adding source: pipe:///tmp/multiroom/server/fifo?name=Radio&sampleformat=48000:16:2&codec=flac Oct 27 07:28:16 volumio-main snapserver[25999]: Settings file: "/var/lib/snapserver/.config/snapserver/server.json" Oct 27 07:28:16 volumio-main snapserver[25999]: Using HTTP host name: volumio-main Oct 27 07:28:16 volumio-main snapserver[25999]: Adding service 'Snapcast' Oct 27 07:28:16 volumio-main snapserver[25999]: PcmStream: Radio, sampleFormat: 48000:16:2 Oct 27 07:28:16 volumio-main snapserver[25999]: PipeStream mode: create Oct 27 07:28:16 volumio-main snapserver[25999]: 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"} Oct 27 07:28:16 volumio-main snapserver[25999]: Init - compression level: 2 Oct 27 07:28:16 volumio-main snapserver[25999]: Creating TCP acceptor for address: 0.0.0.0, port: 1705 Oct 27 07:28:16 volumio-main snapserver[25999]: Creating HTTP acceptor for address: 0.0.0.0, port: 1780 Oct 27 07:28:16 volumio-main snapserver[25999]: Creating stream acceptor for address: 0.0.0.0, port: 1704 Oct 27 07:28:16 volumio-main snapserver[25999]: Number of threads: 4, hw threads: 4 Oct 27 07:28:16 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 07:28:16 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Setting this device as multiroomSync server Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Setting another device as multiroomSync client Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:16 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:17 volumio-main snapserver[25999]: State changed: Radio, state: idle => playing Oct 27 07:28:17 volumio-main snapserver[25999]: onStateChanged (Radio): playing Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.258 [Info] (Avahi) (Browser) CACHE_EXHAUSTED Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.311 [Info] (Avahi) (Browser) NEW: service 'Snapcast' of type '_snapcast._tcp' in domain 'local' Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.314 [Info] (Avahi) (Browser) NEW: service 'Snapcast' of type '_snapcast._tcp' in domain 'local' Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.315 [Info] (Avahi) Service 'Snapcast' of type '_snapcast._tcp' in domain 'local': Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.315 [Info] (Avahi) volumio-main.local:1704 (127.0.0.1) Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.316 [Info] (Controller) Found server 127.0.0.1:1704 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.316 [Info] (Connection) Resolving host IP for: 127.0.0.1 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.316 [Info] (Connection) Connecting Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.316 [Notice] (Connection) Connected to 127.0.0.1 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.316 [Info] (Connection) My MAC: "e4:5f:01:26:81:9d", socket: 8 Oct 27 07:28:17 volumio-main snapserver[25999]: StreamServer::NewConnection: 127.0.0.1 Oct 27 07:28:17 volumio-main snapserver[25999]: Hello from e4:5f:01:26:81:9d, host: volumio-main, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.342 [Info] (Controller) ServerSettings - buffer: 1500, latency: 0, volume: 100, muted: 0 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.343 [Info] (Controller) Codec: flac, sampleformat: 48000:16:2 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.343 [Info] (Player) Player name: alsa, device: volumioMultiRoomClient, description: , idx: 14, sharing mode: unspecified, parameters: Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.343 [Info] (Player) Mixer mode: software, parameters: Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.343 [Info] (Player) Sampleformat: 48000:16:2, stream: 48000:16:2 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.343 [Info] (Alsa) Using default buffer_time: 80 ms, default fragments: 4 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.345 [Info] (Alsa) PCM name: volumioMultiRoomClient, sample rate: 48000 Hz, channels: 2, buffer time: 80000 us, periods: 4, period time: 20000 us, period frames: 960 Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.346 [Info] (Stream) No chunks available Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.346 [Info] (Alsa) Failed to get chunk Oct 27 07:28:17 volumio-main snapclient[25993]: 2025-10-27 07-28-17.365 [Info] (Controller) diff to server [ms]: -0.001 Oct 27 07:28:17 volumio-main snapserver[25999]: Service 'Snapcast' successfully established. Oct 27 07:28:18 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:18 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:18 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:18 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:18 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:19 volumio-main snapserver[25999]: StreamServer::NewConnection: 192.168.103.11 Oct 27 07:28:19 volumio-main snapserver[25999]: Hello from d8:3a:dd:aa:c3:9e, host: volumio-sub3web, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Oct 27 07:28:19 volumio-main snapserver[25999]: Error reading message header of length 0: End of file Oct 27 07:28:19 volumio-main snapserver[25999]: onDisconnect: d8:3a:dd:aa:c3:9e Oct 27 07:28:19 volumio-main snapserver[25999]: StreamServer::NewConnection: 192.168.103.6 Oct 27 07:28:19 volumio-main snapserver[25999]: Hello from d8:3a:dd:aa:c4:55, host: volumio-sub2, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Oct 27 07:28:20 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 27 07:28:20 volumio-main volumio[1367]: info: MRS: Setting this device as multiroomSync server Oct 27 07:28:20 volumio-main volumio[1367]: info: MRS: Setting another device as multiroomSync client Oct 27 07:28:20 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:20 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:22 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:22 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:22 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:22 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:23 volumio-main snapserver[25999]: StreamServer::NewConnection: 192.168.103.11 Oct 27 07:28:23 volumio-main snapserver[25999]: Hello from d8:3a:dd:aa:c3:9e, host: volumio-sub3web, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Oct 27 07:28:23 volumio-main snapserver[25999]: Error reading message header of length 0: End of file Oct 27 07:28:23 volumio-main snapserver[25999]: onDisconnect: d8:3a:dd:aa:c3:9e Oct 27 07:28:25 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 07:28:25 volumio-main volumio[1367]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 27 07:28:25 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 27 07:28:25 volumio-main volumio[1367]: info: Received Get System Version Oct 27 07:28:25 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 07:28:25 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:28:25 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:28:25 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:28:25 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:28:25 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:25 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:28:35 volumio-main systemd[1]: systemd-timedated.service: Deactivated successfully. Oct 27 07:28:51 volumio-main volumio[1367]: verbose: New Socket.io Connection to 192.168.103.200 from 192.168.102.109 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:51 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:28:51 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 07:28:51 volumio-main volumio[1367]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 07:28:51 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:28:51 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:28:51 volumio-main volumio[1367]: info: Listing playlists Oct 27 07:28:51 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 27 07:28:53 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:53 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:53 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:53 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:53 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:28:53 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:28:56 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Oct 27 07:28:56 volumio-main volumio[1367]: info: MRS: Setting this device as multiroomSync server Oct 27 07:28:56 volumio-main volumio[1367]: info: MRS: Setting another device as multiroomSync client Oct 27 07:28:56 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:56 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:58 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:58 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:58 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:28:58 volumio-main volumio[1367]: info: MRS: Updating multiroomSync output Oct 27 07:29:00 volumio-main snapserver[25999]: StreamServer::NewConnection: 192.168.103.11 Oct 27 07:29:00 volumio-main snapserver[25999]: Hello from d8:3a:dd:aa:c3:9e, host: volumio-sub3web, v0.26.0, ClientName: Snapclient, OS: Raspbian GNU/Linux 12 (bookworm), Arch: armv7l, Protocol version: 2 Oct 27 07:29:00 volumio-main snapserver[25999]: Error reading message header of length 0: End of file Oct 27 07:29:00 volumio-main snapserver[25999]: onDisconnect: d8:3a:dd:aa:c3:9e Oct 27 07:29:01 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 07:29:12 volumio-main volumio[1367]: info: Discovery: Disconnected from remote: 192.168.103.11 Oct 27 07:29:12 volumio-main volumio[1367]: info: MRS: Cleaning up device: e2546957-9866-41a7-ade1-bca2b696aa5c Oct 27 07:29:12 volumio-main volumio[1367]: info: Removing audio output: Oct 27 07:29:13 volumio-main volumio[1367]: info: Discovery: A device disappeared from network Oct 27 07:29:15 volumio-main sudo[26115]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 27 07:29:15 volumio-main sudo[26115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:29:15 volumio-main sudo[26115]: pam_unix(sudo:session): session closed for user root Oct 27 07:29:15 volumio-main sudo[26117]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 27 07:29:15 volumio-main sudo[26117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 27 07:29:15 volumio-main sudo[26117]: pam_unix(sudo:session): session closed for user root Oct 27 07:29:15 volumio-main volumio[1367]: verbose: New Socket.io Connection to 192.168.103.200 from 192.168.102.109 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetVisibleSources Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 27 07:29:15 volumio-main volumio[1367]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 27 07:29:15 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:29:15 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:29:15 volumio-main volumio[1367]: info: Listing playlists Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 27 07:29:15 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 27 07:29:16 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 07:29:17 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:29:17 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetQueue Oct 27 07:29:17 volumio-main volumio[1367]: info: CoreStateMachine::getQueue Oct 27 07:29:17 volumio-main volumio[1367]: info: CorePlayQueue::getQueue Oct 27 07:29:17 volumio-main volumio[1367]: info: Discovery: Remote connection error: 192.168.103.11 Oct 27 07:29:17 volumio-main volumio[1367]: info: Discovery: Remote connection timeout: 192.168.103.11 Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 27 07:29:18 volumio-main volumio[1367]: info: Received Get System Info Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 07:29:18 volumio-main volumio[1367]: info: Discovery: Getting this device information Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 07:29:18 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:29:18 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:29:18 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:29:18 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:29:20 volumio-main volumio[1367]: info: CoreCommandRouter::volumioGetState Oct 27 07:29:22 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:29:22 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:29:22 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:29:22 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:29:22 volumio-main volumio[1367]: info: Executing endpoint metavolumio Oct 27 07:29:22 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 27 07:29:25 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 27 07:29:25 volumio-main volumio[1367]: info: Discovery: Remote connection error: 192.168.103.11 Oct 27 07:29:25 volumio-main volumio[1367]: info: Discovery: Remote connection timeout: 192.168.103.11 Oct 27 07:29:27 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 27 07:29:27 volumio-main volumio[1367]: info: Preload queue cleared Oct 27 07:29:29 volumio-main volumio[1367]: Searching plugin music_service/webradio Oct 27 07:29:29 volumio-main volumio[1367]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 27 07:29:30 volumio-main volumio[1367]: Use XMLElement.setAttribute instead Oct 27 07:29:30 volumio-main volumio[1367]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:29:30 volumio-main volumio[1367]: TypeError: children[i].attr(...).value is not a function Oct 27 07:29:30 volumio-main volumio[1367]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Oct 27 07:29:30 volumio-main volumio[1367]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 27 07:29:30 volumio-main volumio[1367]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 07:29:30 volumio-main sudo[26168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-10-27 07:28' Oct 27 07:29:30 volumio-main sudo[26168]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"