-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-06-04 22:12:54 CEST. -- Jun 04 22:11:02 svilumio dhcpcd[992]: eth0: using IPv4LL address 169.254.12.227 Jun 04 22:11:02 svilumio dhcpcd[992]: eth0: adding route to 169.254.0.0/16 Jun 04 22:11:02 svilumio dhcpcd[992]: eth0: adding default route Jun 04 22:11:02 svilumio avahi-daemon[613]: Registering new address record for 169.254.12.227 on eth0.IPv4. Jun 04 22:11:02 svilumio dhcpcd[992]: eth0: pid 992 deleted default route Jun 04 22:11:03 svilumio ntpd[820]: Listen normally on 7 eth0 169.254.12.227:123 Jun 04 22:11:03 svilumio ntpd[820]: 147.91.26.20 local addr 192.168.1.2 -> 169.254.12.227 Jun 04 22:11:03 svilumio ntpd[820]: 147.91.8.1 local addr 192.168.1.2 -> 169.254.12.227 Jun 04 22:11:03 svilumio ntpd[820]: 217.24.20.5 local addr 192.168.1.2 -> 169.254.12.227 Jun 04 22:11:03 svilumio ntpd[820]: 195.250.114.105 local addr 192.168.1.2 -> 169.254.12.227 Jun 04 22:11:03 svilumio ntpd[820]: 217.196.104.131 local addr 192.168.1.2 -> 169.254.12.227 Jun 04 22:11:03 svilumio ntpd[820]: 195.178.58.245 local addr 192.168.1.2 -> 169.254.12.227 Jun 04 22:11:03 svilumio ntpd[820]: new interface(s) found: waking up resolver Jun 04 22:11:04 svilumio volumio[1085]: info: Discovery: adding 3eac9081-1fb6-4539-9277-ec9264b6c3c8 Jun 04 22:11:04 svilumio volumio[1085]: info: Discovery: Found device Svilumio Jun 04 22:11:04 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:11:04 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:11:04 svilumio volumio[1085]: info: MRS: Pushing multiroomSync output for this device Jun 04 22:11:04 svilumio volumio[1085]: info: MRS: Pushing multiroomSync output Jun 04 22:11:04 svilumio volumio[1085]: info: Adding audio output: Jun 04 22:11:04 svilumio volumio[1085]: info: Adding audio output: Jun 04 22:11:11 svilumio ntpd[820]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 04 22:11:15 svilumio dhcpcd[992]: eth0: offered 192.168.1.2 from 192.168.1.1 Jun 04 22:11:15 svilumio dhcpcd[992]: eth0: dhcp_sendudp: Network is unreachable Jun 04 22:11:15 svilumio dhcpcd[992]: eth0: leased 192.168.1.2 for 86400 seconds Jun 04 22:11:15 svilumio dhcpcd[992]: eth0: adding route to 192.168.1.0/24 Jun 04 22:11:15 svilumio dhcpcd[992]: eth0: adding default route via 192.168.1.1 Jun 04 22:11:16 svilumio dhcpcd[992]: eth0: pid 992 deleted default route via 192.168.1.1 Jun 04 22:11:17 svilumio ntpd[820]: 147.91.26.20 local addr 169.254.12.227 -> 192.168.1.2 Jun 04 22:11:17 svilumio ntpd[820]: 147.91.8.1 local addr 169.254.12.227 -> 192.168.1.2 Jun 04 22:11:17 svilumio ntpd[820]: 217.24.20.5 local addr 169.254.12.227 -> 192.168.1.2 Jun 04 22:11:17 svilumio ntpd[820]: 195.250.114.105 local addr 169.254.12.227 -> 192.168.1.2 Jun 04 22:11:17 svilumio ntpd[820]: 217.196.104.131 local addr 169.254.12.227 -> 192.168.1.2 Jun 04 22:11:17 svilumio ntpd[820]: 195.178.58.245 local addr 169.254.12.227 -> 192.168.1.2 Jun 04 22:11:21 svilumio volumio[1085]: info: Tunnel connection is inactive, restarting it Jun 04 22:11:21 svilumio volumio[1085]: info: Starting Tunnel 1 Jun 04 22:11:21 svilumio volumio[1085]: info: Starting Tunnel Connection Checker Jun 04 22:11:21 svilumio sudo[19025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 04 22:11:21 svilumio sudo[19025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 22:11:21 svilumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 04 22:11:21 svilumio sudo[19025]: pam_unix(sudo:session): session closed for user root Jun 04 22:11:21 svilumio volumio[1085]: info: Remote SSH Started Jun 04 22:11:21 svilumio autossh[19028]: port set to 0, monitoring disabled Jun 04 22:11:21 svilumio autossh[19028]: starting ssh (count 1) Jun 04 22:11:21 svilumio autossh[19028]: ssh child pid is 19031 Jun 04 22:11:28 svilumio volumio[1085]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 04 22:11:42 svilumio nmbd[774]: [2025/06/04 22:11:42.466508, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jun 04 22:11:42 svilumio nmbd[774]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jun 04 22:11:44 svilumio volumiossh-tunnel[19027]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Jun 04 22:11:44 svilumio autossh[19028]: ssh exited prematurely with status 255; autossh exiting Jun 04 22:11:44 svilumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 04 22:11:44 svilumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 04 22:11:44 svilumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jun 04 22:11:44 svilumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Jun 04 22:11:44 svilumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 04 22:11:44 svilumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 04 22:11:44 svilumio autossh[19069]: port set to 0, monitoring disabled Jun 04 22:11:44 svilumio autossh[19069]: starting ssh (count 1) Jun 04 22:11:44 svilumio autossh[19069]: ssh child pid is 19072 Jun 04 22:11:50 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:11:50 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:03 svilumio ntpd[820]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 04 22:12:08 svilumio volumiossh-tunnel[19068]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Jun 04 22:12:08 svilumio autossh[19069]: ssh exited prematurely with status 255; autossh exiting Jun 04 22:12:08 svilumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 04 22:12:08 svilumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 04 22:12:08 svilumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jun 04 22:12:08 svilumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Jun 04 22:12:08 svilumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 04 22:12:08 svilumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 04 22:12:08 svilumio autossh[19144]: port set to 0, monitoring disabled Jun 04 22:12:08 svilumio autossh[19144]: starting ssh (count 1) Jun 04 22:12:08 svilumio autossh[19144]: ssh child pid is 19147 Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 22:12:21 svilumio volumio[1085]: info: Discovery: Getting this device information Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:21 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 22:12:21 svilumio volumio[1085]: verbose: New Socket.io Connection to 192.168.1.2:3000 from 192.168.1.11 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:21 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 04 22:12:21 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:21 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:22 svilumio sudo[19177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 22:12:22 svilumio sudo[19177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 22:12:22 svilumio sudo[19177]: pam_unix(sudo:session): session closed for user root Jun 04 22:12:22 svilumio sudo[19180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 22:12:22 svilumio sudo[19180]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 22:12:22 svilumio sudo[19180]: pam_unix(sudo:session): session closed for user root Jun 04 22:12:22 svilumio volumio[1085]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.11 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:22 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 22:12:22 svilumio sudo[19183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 22:12:22 svilumio sudo[19183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 22:12:22 svilumio sudo[19183]: pam_unix(sudo:session): session closed for user root Jun 04 22:12:22 svilumio sudo[19186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 22:12:22 svilumio sudo[19186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 22:12:22 svilumio sudo[19186]: pam_unix(sudo:session): session closed for user root Jun 04 22:12:22 svilumio volumio[1085]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.11 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:22 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 22:12:22 svilumio volumio[1085]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 04 22:12:22 svilumio volumio[1085]: info: Listing playlists Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 04 22:12:22 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 22:12:23 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 22:12:23 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 22:12:23 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetQueue Jun 04 22:12:23 svilumio volumio[1085]: info: CoreStateMachine::getQueue Jun 04 22:12:23 svilumio volumio[1085]: info: CorePlayQueue::getQueue Jun 04 22:12:24 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 22:12:24 svilumio volumio[1085]: info: Received Get System Info Jun 04 22:12:24 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 22:12:24 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 22:12:24 svilumio volumio[1085]: info: Discovery: Getting this device information Jun 04 22:12:24 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:24 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:24 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 22:12:24 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 04 22:12:25 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 22:12:25 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 22:12:25 svilumio volumio[1085]: info: Discovery: Getting this device information Jun 04 22:12:25 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:25 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:25 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 22:12:26 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 22:12:26 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 22:12:26 svilumio volumio[1085]: info: Discovery: Getting this device information Jun 04 22:12:26 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:26 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:26 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 22:12:26 svilumio ntpd[820]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 22:12:28 svilumio volumio[1085]: info: Retrieving Cloud Streaming UI Jun 04 22:12:28 svilumio volumio[1085]: info: Getting Tidal Cloud Configuration Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 22:12:28 svilumio volumio[1085]: info: Getting Qobuz Cloud Configuration Jun 04 22:12:28 svilumio volumio[1085]: info: Asking plugin for UI Config Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 22:12:28 svilumio volumio[1085]: info: Getting Spotify Cloud Configuration Jun 04 22:12:28 svilumio volumio[1085]: info: Asking plugin for UI Config Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 22:12:28 svilumio volumio[1085]: info: Saving Spotify Acccount Jun 04 22:12:28 svilumio volumio[1085]: info: Got it Jun 04 22:12:28 svilumio volumio[1085]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 04 22:12:28 svilumio volumio[1085]: info: Got Tidal Cloud Configuration Jun 04 22:12:28 svilumio volumio[1085]: info: Got it Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 22:12:28 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 22:12:29 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 22:12:29 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 04 22:12:30 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 04 22:12:30 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand update Jun 04 22:12:30 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:30 svilumio volumio[1085]: info: Jun 04 22:12:30 svilumio volumio[1085]: ---------------------------- MPD announces state update: update Jun 04 22:12:30 svilumio volumio[1085]: info: sendMpdCommand update took 2 milliseconds Jun 04 22:12:30 svilumio volumio[1085]: info: ControllerMpd::getState Jun 04 22:12:30 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:30 svilumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds Jun 04 22:12:30 svilumio volumio[1085]: info: sendMpdCommand status took 1 milliseconds Jun 04 22:12:30 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:31 svilumio volumio[1085]: info: Command Router : Notfying DB Updatetrue Jun 04 22:12:31 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:31 svilumio volumio[1085]: info: ControllerMpd::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::servicePushState Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 22:12:31 svilumio volumio[1085]: verbose: CURRENT POSITION 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::syncState stateService stop Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:31 svilumio volumio[1085]: info: No code Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:31 svilumio volumio[1085]: info: ------------------------------ 99ms Jun 04 22:12:31 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:31 svilumio volumio[1085]: info: Jun 04 22:12:31 svilumio volumio[1085]: ---------------------------- MPD announces state update: update Jun 04 22:12:31 svilumio volumio[1085]: info: ControllerMpd::getState Jun 04 22:12:31 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:31 svilumio volumio[1085]: info: sendMpdCommand status took 8 milliseconds Jun 04 22:12:31 svilumio volumio[1085]: info: sendMpdCommand status took 9 milliseconds Jun 04 22:12:31 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:31 svilumio volumio[1085]: info: Command Router : Notfying DB Updatefalse Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::Close All Modals sent Jun 04 22:12:31 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:31 svilumio volumio[1085]: info: ControllerMpd::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::servicePushState Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 22:12:31 svilumio volumio[1085]: verbose: CURRENT POSITION 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::syncState stateService stop Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:31 svilumio volumio[1085]: info: No code Jun 04 22:12:31 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:31 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:31 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:31 svilumio volumio[1085]: info: ------------------------------ 43ms Jun 04 22:12:31 svilumio volumiossh-tunnel[19143]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Jun 04 22:12:31 svilumio autossh[19144]: ssh exited prematurely with status 255; autossh exiting Jun 04 22:12:31 svilumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 04 22:12:31 svilumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 04 22:12:31 svilumio systemd[1]: sshtunnel.service: Service RestartSec=100ms expired, scheduling restart. Jun 04 22:12:31 svilumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Jun 04 22:12:31 svilumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 04 22:12:31 svilumio systemd[1]: Started MyVolumio SSH Tunnel. Jun 04 22:12:31 svilumio autossh[19213]: port set to 0, monitoring disabled Jun 04 22:12:31 svilumio autossh[19213]: starting ssh (count 1) Jun 04 22:12:31 svilumio autossh[19213]: ssh child pid is 19216 Jun 04 22:12:33 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 22:12:37 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand update Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:40 svilumio volumio[1085]: info: Jun 04 22:12:40 svilumio volumio[1085]: ---------------------------- MPD announces state update: update Jun 04 22:12:40 svilumio volumio[1085]: info: sendMpdCommand update took 2 milliseconds Jun 04 22:12:40 svilumio volumio[1085]: info: ControllerMpd::getState Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:40 svilumio volumio[1085]: info: Jun 04 22:12:40 svilumio volumio[1085]: ---------------------------- MPD announces state update: update Jun 04 22:12:40 svilumio volumio[1085]: info: ControllerMpd::getState Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:40 svilumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds Jun 04 22:12:40 svilumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:40 svilumio volumio[1085]: info: Command Router : Notfying DB Updatefalse Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::Close All Modals sent Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:40 svilumio volumio[1085]: info: ControllerMpd::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::servicePushState Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 22:12:40 svilumio volumio[1085]: verbose: CURRENT POSITION 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::syncState stateService stop Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:40 svilumio volumio[1085]: info: No code Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:40 svilumio volumio[1085]: info: ------------------------------ 35ms Jun 04 22:12:40 svilumio volumio[1085]: info: sendMpdCommand status took 35 milliseconds Jun 04 22:12:40 svilumio volumio[1085]: info: sendMpdCommand status took 34 milliseconds Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:40 svilumio volumio[1085]: info: Command Router : Notfying DB Updatefalse Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::Close All Modals sent Jun 04 22:12:40 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:40 svilumio volumio[1085]: info: ControllerMpd::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::servicePushState Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 22:12:40 svilumio volumio[1085]: verbose: CURRENT POSITION 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::syncState stateService stop Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:40 svilumio volumio[1085]: info: No code Jun 04 22:12:40 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:40 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:40 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:40 svilumio volumio[1085]: info: ------------------------------ 65ms Jun 04 22:12:41 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 22:12:45 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 22:12:45 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 22:12:45 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 22:12:45 svilumio volumio[1085]: info: Discovery: Getting this device information Jun 04 22:12:45 svilumio volumio[1085]: info: CoreCommandRouter::volumioGetState Jun 04 22:12:45 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:45 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 22:12:49 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 22:12:49 svilumio ntpd[820]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 04 22:12:53 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand update Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:54 svilumio volumio[1085]: info: Jun 04 22:12:54 svilumio volumio[1085]: ---------------------------- MPD announces state update: update Jun 04 22:12:54 svilumio volumio[1085]: info: sendMpdCommand update took 2 milliseconds Jun 04 22:12:54 svilumio volumio[1085]: info: ControllerMpd::getState Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:54 svilumio volumio[1085]: info: Jun 04 22:12:54 svilumio volumio[1085]: ---------------------------- MPD announces state update: update Jun 04 22:12:54 svilumio volumio[1085]: info: ControllerMpd::getState Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::sendMpdCommand status Jun 04 22:12:54 svilumio volumio[1085]: info: sendMpdCommand status took 1 milliseconds Jun 04 22:12:54 svilumio volumio[1085]: info: sendMpdCommand status took 2 milliseconds Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:54 svilumio volumio[1085]: info: Command Router : Notfying DB Updatefalse Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::Close All Modals sent Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:54 svilumio volumio[1085]: info: ControllerMpd::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::servicePushState Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 22:12:54 svilumio volumio[1085]: verbose: CURRENT POSITION 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::syncState stateService stop Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:54 svilumio volumio[1085]: info: No code Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:54 svilumio volumio[1085]: info: ------------------------------ 54ms Jun 04 22:12:54 svilumio volumio[1085]: info: sendMpdCommand status took 53 milliseconds Jun 04 22:12:54 svilumio volumio[1085]: info: sendMpdCommand status took 54 milliseconds Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:54 svilumio volumio[1085]: info: Command Router : Notfying DB Updatefalse Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::Close All Modals sent Jun 04 22:12:54 svilumio volumio[1085]: verbose: ControllerMpd::parseState Jun 04 22:12:54 svilumio volumio[1085]: info: ControllerMpd::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::servicePushState Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 22:12:54 svilumio volumio[1085]: verbose: CURRENT POSITION 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::syncState stateService stop Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::syncState currentStatus stop Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:54 svilumio volumio[1085]: info: No code Jun 04 22:12:54 svilumio volumio[1085]: info: CoreStateMachine::pushState Jun 04 22:12:54 svilumio volumio[1085]: info: CorePlayQueue::getTrack 0 Jun 04 22:12:54 svilumio volumio[1085]: info: CoreCommandRouter::volumioPushState Jun 04 22:12:54 svilumio volumio[1085]: info: ------------------------------ 83ms Jun 04 22:12:54 svilumio volumio[1085]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 22:12:54 svilumio volumio[1085]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Jun 04 22:12:54 svilumio volumio[1085]: code: 'auth/network-request-failed', Jun 04 22:12:54 svilumio volumio[1085]: a: null Jun 04 22:12:54 svilumio volumio[1085]: } Jun 04 22:12:54 svilumio volumio[1085]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 04 22:12:54 svilumio volumiossh-tunnel[19212]: ssh: Could not resolve hostname eu2.myvolumio.org: Device or resource busy Jun 04 22:12:54 svilumio autossh[19213]: ssh exited prematurely with status 255; autossh exiting Jun 04 22:12:54 svilumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Jun 04 22:12:54 svilumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Jun 04 22:12:54 svilumio sudo[19268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-04 22:11 Jun 04 22:12:54 svilumio sudo[19268]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"