Jan 12 18:32:02 volumio volumio[1370]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.153 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:02 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:02 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:02 volumio volumio[1370]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.153 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:02 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:02 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:02 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:02 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 12 18:32:02 volumio sudo[20820]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 12 18:32:02 volumio sudo[20820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:02 volumio sudo[20820]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:02 volumio sudo[20825]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 12 18:32:02 volumio sudo[20825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:02 volumio sudo[20825]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:02 volumio sudo[20832]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 12 18:32:02 volumio sudo[20832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:02 volumio sudo[20838]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 12 18:32:02 volumio sudo[20838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:02 volumio sudo[20832]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:02 volumio sudo[20838]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:02 volumio sudo[20844]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 18:32:02 volumio sudo[20845]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 18:32:02 volumio sudo[20845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:02 volumio sudo[20844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:02 volumio sudo[20845]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:02 volumio sudo[20844]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:02 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:02 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:02 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:02 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:02 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:02 volumio volumio[1370]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.153 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 18:32:02 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 18:32:04 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 18:32:04 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 18:32:04 volumio sudo[20857]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 18:32:04 volumio sudo[20857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:04 volumio sudo[20857]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:04 volumio sudo[20859]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 18:32:04 volumio sudo[20859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:04 volumio sudo[20859]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:04 volumio volumio[1370]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.153 UA: Mozilla/5.0 (Linux; Android 12; SM-G973F Build/SP1A.210812.016; 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 12 18:32:05 volumio sudo[20863]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 18:32:05 volumio sudo[20863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:05 volumio sudo[20863]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:05 volumio sudo[20865]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 18:32:05 volumio sudo[20865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:05 volumio sudo[20865]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:05 volumio volumio[1370]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.153 UA: Mozilla/5.0 (Linux; Android 12; SM-G973F Build/SP1A.210812.016; 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: 11 Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:05 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 18:32:05 volumio volumio[1370]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 12 18:32:05 volumio volumio[1370]: info: Received Get System Info Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:05 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:05 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:05 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:05 volumio volumio[1370]: info: Listing playlists Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 18:32:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 12 18:32:06 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 18:32:07 volumio volumio[1370]: info: Received Get System Info Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:07 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:07 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 18:32:07 volumio volumio[1370]: info: Received Get System Info Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:07 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:07 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:07 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:10 volumio volumio[1370]: info: Tunnel connection is inactive, restarting it Jan 12 18:32:10 volumio volumio[1370]: info: Starting Tunnel 1 Jan 12 18:32:10 volumio volumio[1370]: info: Starting Tunnel Connection Checker Jan 12 18:32:10 volumio sudo[20898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 12 18:32:10 volumio sudo[20898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 12 18:32:10 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:10 volumio sudo[20898]: pam_unix(sudo:session): session closed for user root Jan 12 18:32:10 volumio volumio[1370]: info: Remote SSH Started Jan 12 18:32:10 volumio autossh[20901]: port set to 0, monitoring disabled Jan 12 18:32:10 volumio autossh[20901]: starting ssh (count 1) Jan 12 18:32:10 volumio autossh[20901]: ssh child pid is 20904 Jan 12 18:32:10 volumio volumio[1370]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 12 18:32:10 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:10 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:10 volumio volumiossh-tunnel[20904]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 18:32:10 volumio autossh[20901]: ssh exited prematurely with status 255; autossh exiting Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jan 12 18:32:10 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:10 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:10 volumio autossh[20908]: port set to 0, monitoring disabled Jan 12 18:32:10 volumio autossh[20908]: starting ssh (count 1) Jan 12 18:32:10 volumio autossh[20908]: ssh child pid is 20911 Jan 12 18:32:10 volumio volumiossh-tunnel[20911]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 18:32:10 volumio autossh[20908]: ssh exited prematurely with status 255; autossh exiting Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jan 12 18:32:10 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:10 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:10 volumio autossh[20913]: port set to 0, monitoring disabled Jan 12 18:32:10 volumio autossh[20913]: starting ssh (count 1) Jan 12 18:32:10 volumio autossh[20913]: ssh child pid is 20916 Jan 12 18:32:10 volumio volumiossh-tunnel[20916]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 18:32:10 volumio autossh[20913]: ssh exited prematurely with status 255; autossh exiting Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 18:32:10 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 18:32:11 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jan 12 18:32:11 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:11 volumio autossh[20920]: port set to 0, monitoring disabled Jan 12 18:32:11 volumio autossh[20920]: starting ssh (count 1) Jan 12 18:32:11 volumio autossh[20920]: ssh child pid is 20923 Jan 12 18:32:11 volumio volumiossh-tunnel[20923]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 18:32:11 volumio autossh[20920]: ssh exited prematurely with status 255; autossh exiting Jan 12 18:32:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 18:32:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 18:32:11 volumio volumio[1370]: info: CoreCommandRouter::volumioGetQueue Jan 12 18:32:11 volumio volumio[1370]: info: CoreStateMachine::getQueue Jan 12 18:32:11 volumio volumio[1370]: info: CorePlayQueue::getQueue Jan 12 18:32:11 volumio volumio[1370]: info: Executing endpoint metavolumio Jan 12 18:32:11 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 18:32:11 volumio volumio[1370]: info: Executing endpoint metavolumio Jan 12 18:32:11 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 12 18:32:11 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Jan 12 18:32:11 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:11 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:11 volumio autossh[20925]: port set to 0, monitoring disabled Jan 12 18:32:11 volumio autossh[20925]: starting ssh (count 1) Jan 12 18:32:11 volumio autossh[20925]: ssh child pid is 20928 Jan 12 18:32:11 volumio volumiossh-tunnel[20928]: ssh: connect to host eu7.myvolumio.org port 2222: Connection refused Jan 12 18:32:11 volumio autossh[20925]: ssh exited prematurely with status 255; autossh exiting Jan 12 18:32:11 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jan 12 18:32:11 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 18:32:12 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Jan 12 18:32:12 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:12 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Jan 12 18:32:12 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jan 12 18:32:12 volumio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Jan 12 18:32:13 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:32:13 volumio volumio[1370]: info: CURURI: playlists Jan 12 18:32:13 volumio volumio[1370]: info: Listing playlists Jan 12 18:32:13 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:15 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:32:15 volumio volumio[1370]: info: CURURI: playlists/Electronica Jan 12 18:32:15 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:15 volumio volumio[1370]: info: Preloading song: tidal://song/90965666 Jan 12 18:32:15 volumio volumio[1370]: info: Exploding uri tidal://song/90965666 in service tidal Jan 12 18:32:15 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 12 18:32:15 volumio volumio[1370]: info: explodeTIDALUri took 357 milliseconds Jan 12 18:32:19 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:32:19 volumio volumio[1370]: info: CURURI: playlists/Funk Soul Jan 12 18:32:19 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:19 volumio volumio[1370]: info: Preloading song: tidal://song/1747933 Jan 12 18:32:19 volumio volumio[1370]: info: Exploding uri tidal://song/1747933 in service tidal Jan 12 18:32:19 volumio volumio[1370]: info: explodeTIDALUri took 207 milliseconds Jan 12 18:32:22 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:22 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:22 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:22 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:22 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:22 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:22 volumio volumio[1370]: verbose: New Socket.io Connection to 192.168.1.52:3000 from 192.168.1.153 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Jan 12 18:32:22 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 18:32:22 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 18:32:24 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::ClearQueue Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::stop Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::updateTrackBlock Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrackBlock Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::pushState Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioPushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::serviceStop Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 6 Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::serviceStop Jan 12 18:32:24 volumio volumio[1370]: info: [1768239144452] ControllerTidal::stop Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:32:24 volumio volumio[1370]: info: ControllerMpd::stop Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::clearPlayQueue Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::saveQueue Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioPushQueue Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::addQueueItems Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::addQueueItems Jan 12 18:32:24 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:24 volumio volumio[1370]: info: Adding Item to queue: tidal://song/1747933 Jan 12 18:32:24 volumio volumio[1370]: info: Using cached record of: tidal://song/1747933 Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioPushQueue Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::saveQueue Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::updateTrackBlock Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrackBlock Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioPlay Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::play index 0 Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::stop Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::play index undefined Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::startPlaybackTimer Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 18:32:24 volumio volumio[1370]: info: [1768239144464] ControllerTidal::clearAddPlayTrack Jan 12 18:32:24 volumio volumio[1370]: info: Getting stream with soundQuality LOSSLESS Jan 12 18:32:24 volumio volumio[1370]: info: Jan 12 18:32:24 volumio volumio[1370]: ---------------------------- MPD announces state update: player Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand stop took 58 milliseconds Jan 12 18:32:24 volumio volumio[1370]: info: ControllerMpd::getState Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:32:24 volumio volumio[1370]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand status took 6 milliseconds Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::parseState Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:32:24 volumio volumio[1370]: info: ControllerMpd::pushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::servicePushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::pushState Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioPushState Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:32:24 volumio volumio[1370]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jan 12 18:32:24 volumio volumio[1370]: info: ------------------------------ 28ms Jan 12 18:32:24 volumio volumio[1370]: info: getStreamUrl took 226 milliseconds Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand stop took 1 milliseconds Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 18:32:24 volumio volumio[1370]: info: Jan 12 18:32:24 volumio volumio[1370]: ---------------------------- MPD announces system playlist update Jan 12 18:32:24 volumio volumio[1370]: info: Ignoring MPD Status Update Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand clear took 1 milliseconds Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmQzZDBiMzlmNDJkODJiZGVmNDEwM2Q5YTUwMDBmZC5tcDQ/0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==" Jan 12 18:32:24 volumio volumio[1370]: error: updateQueue error: null Jan 12 18:32:24 volumio volumio[1370]: info: Jan 12 18:32:24 volumio volumio[1370]: ---------------------------- MPD announces system playlist update Jan 12 18:32:24 volumio volumio[1370]: info: Ignoring MPD Status Update Jan 12 18:32:24 volumio volumio[1370]: info: ------------------------------ 1ms Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmQzZDBiMzlmNDJkODJiZGVmNDEwM2Q5YTUwMDBmZC5tcDQ/0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==" took 1 milliseconds Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand play Jan 12 18:32:24 volumio volumio[1370]: info: ------------------------------ 1ms Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand play took 3 milliseconds Jan 12 18:32:24 volumio volumio[1370]: info: Jan 12 18:32:24 volumio volumio[1370]: ---------------------------- MPD announces state update: player Jan 12 18:32:24 volumio volumio[1370]: info: ControllerMpd::getState Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:32:24 volumio volumio[1370]: info: Jan 12 18:32:24 volumio volumio[1370]: ---------------------------- MPD announces state update: player Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand status took 4 milliseconds Jan 12 18:32:24 volumio volumio[1370]: info: ControllerMpd::getState Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::parseState Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::parseState Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:32:24 volumio volumio[1370]: info: ControllerMpd::pushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::servicePushState Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:32:24 volumio volumio[1370]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmQzZDBiMzlmNDJkODJiZGVmNDEwM2Q5YTUwMDBmZC5tcDQ/0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","trackType":"tidal"} Jan 12 18:32:24 volumio volumio[1370]: verbose: CURRENT POSITION 0 Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::syncState stateService play Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::syncState currentStatus stop Jan 12 18:32:24 volumio volumio[1370]: info: ------------------------------ 6ms Jan 12 18:32:24 volumio volumio[1370]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 12 18:32:24 volumio volumio[1370]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:32:24 volumio volumio[1370]: info: ControllerMpd::pushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::servicePushState Jan 12 18:32:24 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:32:24 volumio volumio[1370]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmQzZDBiMzlmNDJkODJiZGVmNDEwM2Q5YTUwMDBmZC5tcDQ/0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","trackType":"tidal"} Jan 12 18:32:24 volumio volumio[1370]: verbose: CURRENT POSITION 0 Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::syncState stateService play Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::syncState currentStatus play Jan 12 18:32:24 volumio volumio[1370]: info: Received an update from plugin. extracting info from payload Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::pushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioPushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreStateMachine::pushState Jan 12 18:32:24 volumio volumio[1370]: info: CoreCommandRouter::volumioPushState Jan 12 18:32:24 volumio volumio[1370]: info: ------------------------------ 21ms Jan 12 18:32:24 volumio volumio[1370]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 18:32:33 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 18:32:33 volumio volumio[1370]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 12 18:32:33 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 12 18:32:33 volumio volumio[1370]: info: Received Get System Version Jan 12 18:32:33 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 18:32:33 volumio volumio[1370]: info: Received Get System Info Jan 12 18:32:33 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 18:32:33 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 18:32:33 volumio volumio[1370]: info: Discovery: Getting this device information Jan 12 18:32:33 volumio volumio[1370]: info: CoreCommandRouter::volumioGetState Jan 12 18:32:33 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 18:32:36 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:32:36 volumio volumio[1370]: info: CURURI: playlists/Hun Jan 12 18:32:36 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:36 volumio volumio[1370]: info: Preloading song: tidal://album/162379067 Jan 12 18:32:36 volumio volumio[1370]: info: Preloading song: tidal://song/373679645 Jan 12 18:32:36 volumio volumio[1370]: info: Preloading song: tidal://album/231806526 Jan 12 18:32:36 volumio volumio[1370]: info: Preloading song: tidal://song/217117750 Jan 12 18:32:36 volumio volumio[1370]: info: Exploding uri tidal://album/162379067 in service tidal Jan 12 18:32:36 volumio volumio[1370]: info: Exploding uri tidal://song/373679645 in service tidal Jan 12 18:32:36 volumio volumio[1370]: info: Exploding uri tidal://album/231806526 in service tidal Jan 12 18:32:36 volumio volumio[1370]: info: Exploding uri tidal://song/217117750 in service tidal Jan 12 18:32:36 volumio volumio[1370]: info: explodeTIDALUri took 365 milliseconds Jan 12 18:32:36 volumio volumio[1370]: info: explodeTIDALUri took 527 milliseconds Jan 12 18:32:36 volumio volumio[1370]: info: explodeTIDALUri took 402 milliseconds Jan 12 18:32:36 volumio volumio[1370]: info: explodeTIDALUri took 511 milliseconds Jan 12 18:32:42 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:32:42 volumio volumio[1370]: info: CURURI: playlists/Soundtrack Jan 12 18:32:42 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:42 volumio volumio[1370]: info: Preloading song: tidal://album/285570149 Jan 12 18:32:42 volumio volumio[1370]: info: Preloading song: tidal://song/365301347 Jan 12 18:32:42 volumio volumio[1370]: info: Exploding uri tidal://album/285570149 in service tidal Jan 12 18:32:42 volumio volumio[1370]: info: Exploding uri tidal://song/365301347 in service tidal Jan 12 18:32:42 volumio volumio[1370]: info: explodeTIDALUri took 334 milliseconds Jan 12 18:32:43 volumio volumio[1370]: info: explodeTIDALUri took 536 milliseconds Jan 12 18:32:51 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:32:51 volumio volumio[1370]: info: CURURI: playlists/Acid Jan 12 18:32:51 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:32:51 volumio volumio[1370]: info: Preloading song: tidal://song/278013519 Jan 12 18:32:51 volumio volumio[1370]: info: Preloading song: tidal://song/4855585 Jan 12 18:32:51 volumio volumio[1370]: info: Preloading song: tidal://song/312359799 Jan 12 18:32:51 volumio volumio[1370]: info: Exploding uri tidal://song/278013519 in service tidal Jan 12 18:32:51 volumio volumio[1370]: info: Exploding uri tidal://song/4855585 in service tidal Jan 12 18:32:51 volumio volumio[1370]: info: Exploding uri tidal://song/312359799 in service tidal Jan 12 18:32:52 volumio volumio[1370]: info: explodeTIDALUri took 318 milliseconds Jan 12 18:32:52 volumio volumio[1370]: info: explodeTIDALUri took 321 milliseconds Jan 12 18:32:52 volumio volumio[1370]: info: explodeTIDALUri took 351 milliseconds Jan 12 18:33:03 volumio volumio[1370]: info: CoreCommandRouter::volumioPause Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::pause Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::servicePause Jan 12 18:33:03 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:33:03 volumio volumio[1370]: info: CoreCommandRouter::servicePause Jan 12 18:33:03 volumio volumio[1370]: info: [1768239183751] ControllerTidal::pause Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:33:03 volumio volumio[1370]: info: ControllerMpd::pause Jan 12 18:33:03 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand pause Jan 12 18:33:03 volumio volumio[1370]: info: Jan 12 18:33:03 volumio volumio[1370]: ---------------------------- MPD announces state update: player Jan 12 18:33:03 volumio volumio[1370]: info: sendMpdCommand pause took 2 milliseconds Jan 12 18:33:03 volumio volumio[1370]: info: ControllerMpd::getState Jan 12 18:33:03 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:33:03 volumio volumio[1370]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:33:03 volumio volumio[1370]: verbose: ControllerMpd::parseState Jan 12 18:33:03 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:33:03 volumio volumio[1370]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:33:03 volumio volumio[1370]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:33:03 volumio volumio[1370]: info: ControllerMpd::pushState Jan 12 18:33:03 volumio volumio[1370]: info: CoreCommandRouter::servicePushState Jan 12 18:33:03 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:33:03 volumio volumio[1370]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":39729,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"876 Kbps","isStreaming":false,"title":"0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmQzZDBiMzlmNDJkODJiZGVmNDEwM2Q5YTUwMDBmZC5tcDQ/0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","trackType":"tidal"} Jan 12 18:33:03 volumio volumio[1370]: verbose: CURRENT POSITION 0 Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::syncState stateService pause Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::syncState currentStatus pause Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::pushState Jan 12 18:33:03 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:33:03 volumio volumio[1370]: info: CoreCommandRouter::volumioPushState Jan 12 18:33:03 volumio volumio[1370]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:33:03 volumio volumio[1370]: info: ------------------------------ 7ms Jan 12 18:33:03 volumio volumio[1370]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 18:33:05 volumio volumio[1370]: info: CoreCommandRouter::volumioPlay Jan 12 18:33:05 volumio volumio[1370]: info: CoreStateMachine::play index undefined Jan 12 18:33:05 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:33:05 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:33:05 volumio volumio[1370]: info: CoreStateMachine::startPlaybackTimer Jan 12 18:33:05 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:33:05 volumio volumio[1370]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 18:33:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 18:33:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 18:33:05 volumio volumio[1370]: info: [1768239185608] ControllerTidal::resume Jan 12 18:33:05 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:33:05 volumio volumio[1370]: info: ControllerMpd::resume Jan 12 18:33:05 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand play Jan 12 18:33:05 volumio volumio[1370]: info: Jan 12 18:33:05 volumio volumio[1370]: ---------------------------- MPD announces state update: player Jan 12 18:33:05 volumio volumio[1370]: info: sendMpdCommand play took 5 milliseconds Jan 12 18:33:05 volumio volumio[1370]: info: ControllerMpd::getState Jan 12 18:33:05 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand status Jan 12 18:33:05 volumio volumio[1370]: info: sendMpdCommand status took 1 milliseconds Jan 12 18:33:05 volumio volumio[1370]: verbose: ControllerMpd::parseState Jan 12 18:33:05 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 18:33:05 volumio volumio[1370]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 12 18:33:05 volumio volumio[1370]: verbose: ControllerMpd::parseTrackInfo Jan 12 18:33:05 volumio volumio[1370]: info: ControllerMpd::pushState Jan 12 18:33:05 volumio volumio[1370]: info: CoreCommandRouter::servicePushState Jan 12 18:33:05 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:33:05 volumio volumio[1370]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40600,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"941 Kbps","isStreaming":false,"title":"0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmQzZDBiMzlmNDJkODJiZGVmNDEwM2Q5YTUwMDBmZC5tcDQ/0.flac?token=1768242744~NjNkMzAyYjRlNjgwYWQ0YzRhMzJlMzAyNzE1MmI4ZDg3ZmQ0ZWUwNg==","trackType":"tidal"} Jan 12 18:33:05 volumio volumio[1370]: verbose: CURRENT POSITION 0 Jan 12 18:33:05 volumio volumio[1370]: info: CoreStateMachine::syncState stateService play Jan 12 18:33:05 volumio volumio[1370]: info: CoreStateMachine::syncState currentStatus pause Jan 12 18:33:05 volumio volumio[1370]: info: CoreStateMachine::pushState Jan 12 18:33:05 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:33:05 volumio volumio[1370]: info: CoreCommandRouter::volumioPushState Jan 12 18:33:05 volumio volumio[1370]: info: ------------------------------ 8ms Jan 12 18:33:05 volumio volumio[1370]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 12 18:33:24 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:33:24 volumio volumio[1370]: info: CURURI: music-library Jan 12 18:33:24 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:33:25 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:33:25 volumio volumio[1370]: info: CURURI: music-library/USB Jan 12 18:33:25 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:33:26 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:33:26 volumio volumio[1370]: info: CURURI: music-library/USB/Music Jan 12 18:33:26 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:33:29 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 18:33:29 volumio volumio[1370]: info: CURURI: music-library/USB/Music/Rock Jan 12 18:33:29 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:33:44 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:33:44 volumio volumio[1370]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::ClearQueue Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::stop Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::stPlaybackTimer Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::updateTrackBlock Jan 12 18:33:44 volumio volumio[1370]: info: CorePlayQueue::getTrackBlock Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::pushState Jan 12 18:33:44 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:33:44 volumio volumio[1370]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 18:33:44 volumio volumio[1370]: info: CoreCommandRouter::volumioPushState Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::serviceStop Jan 12 18:33:44 volumio volumio[1370]: info: CorePlayQueue::getTrack 0 Jan 12 18:33:44 volumio volumio[1370]: info: CoreCommandRouter::serviceStop Jan 12 18:33:44 volumio volumio[1370]: info: [1768239224126] ControllerTidal::stop Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 12 18:33:44 volumio volumio[1370]: info: ControllerMpd::stop Jan 12 18:33:44 volumio volumio[1370]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 18:33:44 volumio volumio[1370]: info: CorePlayQueue::clearPlayQueue Jan 12 18:33:44 volumio volumio[1370]: info: CorePlayQueue::saveQueue Jan 12 18:33:44 volumio volumio[1370]: info: CoreCommandRouter::volumioPushQueue Jan 12 18:33:44 volumio volumio[1370]: info: CoreStateMachine::addQueueItems Jan 12 18:33:44 volumio volumio[1370]: info: CorePlayQueue::addQueueItems Jan 12 18:33:44 volumio volumio[1370]: info: Preload queue cleared Jan 12 18:33:44 volumio volumio[1370]: info: Adding Item to queue: music-library/USB/Music/Rock/Mike Oldfield - The Songs Of Distant Earth 1994 Jan 12 18:33:44 volumio volumio[1370]: info: Exploding uri music-library/USB/Music/Rock/Mike Oldfield - The Songs Of Distant Earth 1994 in service mpd Jan 12 18:33:44 volumio volumio[1370]: info: Jan 12 18:33:44 volumio volumio[1370]: ---------------------------- MPD announces state update: player Jan 12 18:33:44 volumio volumio[1370]: info: ALBUMART /albumart?cacheid=462&web=Mike%20Oldfield/The%20Songs%20Of%20Distant%20Earth/extralarge&path=%2Fmnt%2FUSB%2FMusic%2FRock%2FMike%20Oldfield%20-%20The%20Songs%20Of%20Distant%20Earth%201994%2FMike%20Oldfield%20-%20The%20Songs%20Of%20Distant%20Earth.cue&metadata=false Jan 12 18:33:44 volumio volumio[1370]: info: URI /mnt/USB/Music/Rock/Mike Oldfield - The Songs Of Distant Earth 1994/Mike Oldfield - The Songs Of Distant Earth.cue Jan 12 18:33:44 volumio volumio[1370]: info: ALBUMART /albumart?cacheid=462&web=Mike%20Oldfield/The%20Songs%20Of%20Distant%20Earth/extralarge&path=%2Fmnt%2FUSB%2FMusic%2FRock%2FMike%20Oldfield%20-%20The%20Songs%20Of%20Distant%20Earth%201994%2FMike%20Oldfield%20-%20The%20Songs%20Of%20Distant%20Earth.cue&metadata=false Jan 12 18:33:44 volumio volumio[1370]: info: URI /mnt/USB/Music/Rock/Mike Oldfield - The Songs Of Distant Earth 1994/Mike Oldfield - The Songs Of Distant Earth.cue Jan 12 18:33:44 volumio volumio[1370]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 18:33:44 volumio volumio[1370]: Error: Unable to resolve or reject the same promise twice Jan 12 18:33:44 volumio volumio[1370]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 12 18:33:44 volumio volumio[1370]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Jan 12 18:33:44 volumio volumio[1370]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 12 18:33:44 volumio volumio[1370]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 12 18:33:44 volumio volumio[1370]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 12 18:33:44 volumio volumio[1370]: at Socket.emit (node:events:514:28) Jan 12 18:33:44 volumio volumio[1370]: at addChunk (node:internal/streams/readable:343:12) Jan 12 18:33:44 volumio volumio[1370]: at readableAddChunk (node:internal/streams/readable:312:11) Jan 12 18:33:44 volumio volumio[1370]: at Readable.push (node:internal/streams/readable:253:10) Jan 12 18:33:44 volumio volumio[1370]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jan 12 18:33:44 volumio volumio[1370]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 18:33:44 volumio sudo[21337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 18:32' Jan 12 18:33:44 volumio sudo[21337]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"