-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-04-19 15:04:42 CEST. -- Apr 19 15:03:00 volumio-joe volumio[1029]: info: Volumio Network Manager: Network status updated: 1 Apr 19 15:03:03 volumio-joe dhcpcd[934]: eth0: offered 192.168.8.103 from 192.168.8.1 Apr 19 15:03:04 volumio-joe dhcpcd[934]: eth0: probing address 192.168.8.103/24 Apr 19 15:03:05 volumio-joe dhcpcd[979]: eth0: offered 192.168.8.103 from 192.168.8.1 Apr 19 15:03:05 volumio-joe dhcpcd[979]: eth0: probing address 192.168.8.103/24 Apr 19 15:03:07 volumio-joe wpa_supplicant[879]: wlan0: Failed to initiate sched scan Apr 19 15:03:09 volumio-joe dhcpcd[934]: eth0: leased 192.168.8.103 for 3600 seconds Apr 19 15:03:09 volumio-joe dhcpcd[934]: eth0: adding route to 192.168.8.0/24 Apr 19 15:03:09 volumio-joe avahi-daemon[606]: Registering new address record for 192.168.8.103 on eth0.IPv4. Apr 19 15:03:09 volumio-joe dhcpcd[934]: eth0: changing default route via 192.168.8.1 Apr 19 15:03:09 volumio-joe dhcpcd[979]: eth0: pid 934 deleted default route Apr 19 15:03:09 volumio-joe avahi-daemon[606]: Withdrawing address record for 169.254.154.166 on eth0. Apr 19 15:03:09 volumio-joe avahi-daemon[606]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.154.166. Apr 19 15:03:09 volumio-joe avahi-daemon[606]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.8.103. Apr 19 15:03:09 volumio-joe dhcpcd[934]: eth0: deleting route to 169.254.0.0/16 Apr 19 15:03:09 volumio-joe avahi-daemon[606]: Withdrawing address record for 169.254.34.34 on eth0. Apr 19 15:03:09 volumio-joe dhcpcd[979]: eth0: pid 0 deleted route to 169.254.0.0/16 Apr 19 15:03:09 volumio-joe dhcpcd[979]: eth0: adding route to 169.254.0.0/16 Apr 19 15:03:09 volumio-joe dhcpcd[979]: if_route (ADD): Invalid argument Apr 19 15:03:09 volumio-joe dhcpcd[979]: eth0: adding default route Apr 19 15:03:09 volumio-joe dhcpcd[979]: if_route (ADD): Invalid argument Apr 19 15:03:09 volumio-joe dhcpcd[979]: eth0: pid 934 deleted IP address 169.254.34.34/16 Apr 19 15:03:09 volumio-joe dhcpcd[934]: eth0: pid 934 deleted default route via 192.168.8.1 Apr 19 15:03:09 volumio-joe dhcpcd[979]: eth0: probing for an IPv4LL address Apr 19 15:03:10 volumio-joe dhcpcd[979]: eth0: leased 192.168.8.103 for 3600 seconds Apr 19 15:03:10 volumio-joe dhcpcd[979]: eth0: adding route to 192.168.8.0/24 Apr 19 15:03:10 volumio-joe dhcpcd[979]: eth0: adding default route via 192.168.8.1 Apr 19 15:03:11 volumio-joe sudo[2504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:03:11 volumio-joe sudo[2504]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:11 volumio-joe sudo[2504]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:11 volumio-joe sudo[2506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:03:11 volumio-joe sudo[2506]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:11 volumio-joe sudo[2506]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:11 volumio-joe ntpd[754]: Listen normally on 7 eth0 192.168.8.103:123 Apr 19 15:03:11 volumio-joe ntpd[754]: Deleting interface #5 eth0, 169.254.154.166#123, interface stats: received=0, sent=14, dropped=0, active_time=14 secs Apr 19 15:03:11 volumio-joe ntpd[754]: 212.6.50.243 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 162.159.200.123 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 162.159.200.1 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 185.157.229.254 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 93.94.88.51 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 151.22.209.90 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 185.19.184.35 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 89.46.74.148 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 212.45.144.3 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 217.61.62.224 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 85.199.214.99 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 95.110.254.234 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 95.110.135.141 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: 5.249.144.95 local addr 169.254.154.166 -> Apr 19 15:03:11 volumio-joe ntpd[754]: Deleting interface #6 eth0, 169.254.34.34#123, interface stats: received=0, sent=0, dropped=0, active_time=12 secs Apr 19 15:03:11 volumio-joe ntpd[754]: new interface(s) found: waking up resolver Apr 19 15:03:12 volumio-joe volumio[1029]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 19 15:03:12 volumio-joe volumio[1029]: verbose: New Socket.io Connection to 192.168.8.103 from 192.168.8.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Apr 19 15:03:14 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 15:03:14 volumio-joe sudo[2510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:03:14 volumio-joe sudo[2510]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:14 volumio-joe sudo[2510]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:14 volumio-joe sudo[2512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:03:14 volumio-joe sudo[2512]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:14 volumio-joe sudo[2512]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:14 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 15:03:15 volumio-joe wpa_supplicant[879]: wlan0: Failed to initiate sched scan Apr 19 15:03:15 volumio-joe volumio[1029]: verbose: New Socket.io Connection to 192.168.8.103 from 192.168.8.196 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:16 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 15:03:16 volumio-joe volumio[1029]: info: Received Get System Info Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:03:16 volumio-joe volumio[1029]: info: Discovery: Getting this device information Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:16 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:16 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:16 volumio-joe volumio[1029]: info: Listing playlists Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 15:03:16 volumio-joe volumio[1029]: info: Received Get System Info Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:03:16 volumio-joe volumio[1029]: info: Discovery: Getting this device information Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:16 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 19 15:03:16 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:16 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:18 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 19 15:03:18 volumio-joe ntpd[754]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 19 15:03:18 volumio-joe ntpd[754]: Soliciting pool server 212.45.144.88 Apr 19 15:03:18 volumio-joe ntpd[754]: Soliciting pool server 129.152.16.145 Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPlay Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreStateMachine::play index undefined Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 15:03:19 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreStateMachine::startPlaybackTimer Apr 19 15:03:19 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:19 volumio-joe volumio[1029]: info: [1745067799759] ControllerWebradio::clearAddPlayTrack Apr 19 15:03:19 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 15:03:19 volumio-joe volumio[1029]: info: sendMpdCommand stop took 3 milliseconds Apr 19 15:03:19 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand clear Apr 19 15:03:19 volumio-joe volumio[1029]: info: sendMpdCommand clear took 1 milliseconds Apr 19 15:03:19 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand load "https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3" Apr 19 15:03:19 volumio-joe volumio[1029]: info: Apr 19 15:03:19 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:19 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:19 volumio-joe volumio[1029]: info: Apr 19 15:03:19 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:19 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:19 volumio-joe volumio[1029]: info: Apr 19 15:03:19 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:19 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:19 volumio-joe volumio[1029]: error: Upnp client error: Error: This socket has been ended by the other party Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 19 15:03:19 volumio-joe volumio[1029]: info: Received Get System Info Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:03:19 volumio-joe volumio[1029]: info: Discovery: Getting this device information Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:19 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:19 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand add "https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3" Apr 19 15:03:20 volumio-joe volumio[1029]: error: updateQueue error: null Apr 19 15:03:20 volumio-joe volumio[1029]: error: updateQueue error: null Apr 19 15:03:20 volumio-joe volumio[1029]: error: updateQueue error: null Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 966ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 963ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 961ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand add "https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3" took 3 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 7ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand play took 5 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 4ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 3ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 16 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 19 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 17 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"gammaradio.mp3?FLID=3","artist":null,"album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus stop Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 29ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 10 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 3 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 2 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"gammaradio.mp3?FLID=3","artist":null,"album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"gammaradio.mp3?FLID=3","artist":null,"album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 59ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 58ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"gammaradio.mp3?FLID=3","artist":null,"album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"gammaradio.mp3?FLID=3","artist":null,"album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"gammaradio.mp3?FLID=3","artist":null,"album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 80ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 73ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 73ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:20 volumio-joe volumio[1029]: info: Apr 19 15:03:20 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 52ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 51 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 6ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 5 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 3ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand status took 2 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"*WWW.GAMMARADIO.IT* ","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"*WWW.GAMMARADIO.IT* ","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"*WWW.GAMMARADIO.IT* ","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:20 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:20 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 85ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 39ms Apr 19 15:03:20 volumio-joe volumio[1029]: info: ------------------------------ 36ms Apr 19 15:03:23 volumio-joe wpa_supplicant[879]: wlan0: Failed to initiate sched scan Apr 19 15:03:24 volumio-joe volumio[1029]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:03:24 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 19 15:03:24 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 19 15:03:25 volumio-joe sudo[2553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 19 15:03:25 volumio-joe sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:25 volumio-joe sudo[2558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 19 15:03:25 volumio-joe sudo[2558]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:25 volumio-joe sudo[2553]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:25 volumio-joe sudo[2558]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:25 volumio-joe sudo[2565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 19 15:03:25 volumio-joe sudo[2565]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:25 volumio-joe sudo[2565]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:25 volumio-joe sudo[2571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 19 15:03:25 volumio-joe sudo[2571]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:25 volumio-joe sudo[2571]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:25 volumio-joe sudo[2578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:03:25 volumio-joe sudo[2578]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:25 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 19 15:03:25 volumio-joe sudo[2578]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:25 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 19 15:03:25 volumio-joe sudo[2582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:03:25 volumio-joe sudo[2582]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:25 volumio-joe sudo[2582]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:25 volumio-joe sudo[2585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 19 15:03:25 volumio-joe sudo[2585]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:25 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 19 15:03:26 volumio-joe sudo[2585]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:32 volumio-joe wpa_supplicant[879]: wlan0: Failed to initiate sched scan Apr 19 15:03:32 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 19 15:03:32 volumio-joe volumio[1029]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 19 15:03:32 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 19 15:03:32 volumio-joe volumio[1029]: info: Received Get System Version Apr 19 15:03:32 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 19 15:03:32 volumio-joe volumio[1029]: info: Received Get System Info Apr 19 15:03:32 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 15:03:32 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 15:03:32 volumio-joe volumio[1029]: info: Discovery: Getting this device information Apr 19 15:03:32 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:03:32 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 15:03:36 volumio-joe volumio[1029]: info: Apr 19 15:03:36 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:36 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:36 volumio-joe volumio[1029]: info: Apr 19 15:03:36 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:36 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:36 volumio-joe volumio[1029]: info: Apr 19 15:03:36 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:36 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:36 volumio-joe volumio[1029]: info: Apr 19 15:03:36 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:36 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:36 volumio-joe volumio[1029]: info: Apr 19 15:03:36 volumio-joe volumio[1029]: ---------------------------- MPD announces system playlist update Apr 19 15:03:36 volumio-joe volumio[1029]: info: Ignoring MPD Status Update Apr 19 15:03:36 volumio-joe volumio[1029]: info: Apr 19 15:03:36 volumio-joe volumio[1029]: ---------------------------- MPD announces state update: player Apr 19 15:03:36 volumio-joe volumio[1029]: info: ControllerMpd::getState Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand status Apr 19 15:03:36 volumio-joe volumio[1029]: info: ------------------------------ 6ms Apr 19 15:03:36 volumio-joe volumio[1029]: info: sendMpdCommand status took 5 milliseconds Apr 19 15:03:36 volumio-joe volumio[1029]: info: ------------------------------ 4ms Apr 19 15:03:36 volumio-joe volumio[1029]: info: sendMpdCommand status took 3 milliseconds Apr 19 15:03:36 volumio-joe volumio[1029]: info: ------------------------------ 3ms Apr 19 15:03:36 volumio-joe volumio[1029]: info: sendMpdCommand status took 3 milliseconds Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::parseState Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 19 15:03:36 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 19 15:03:36 volumio-joe volumio[1029]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: ControllerMpd::parseTrackInfo Apr 19 15:03:36 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:36 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CULTURE CLUB - MISS ME BLIND ","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:36 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:36 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:36 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:37 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CULTURE CLUB - MISS ME BLIND ","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:37 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:37 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: ControllerMpd::pushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreCommandRouter::servicePushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CorePlayQueue::getTrack 0 Apr 19 15:03:37 volumio-joe volumio[1029]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16981,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"CULTURE CLUB - MISS ME BLIND ","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 19 15:03:37 volumio-joe volumio[1029]: verbose: CURRENT POSITION 0 Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::syncState stateService play Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::syncState currentStatus play Apr 19 15:03:37 volumio-joe volumio[1029]: info: Received an update from plugin. extracting info from payload Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreStateMachine::pushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioPushState Apr 19 15:03:37 volumio-joe volumio[1029]: info: ------------------------------ 40ms Apr 19 15:03:37 volumio-joe volumio[1029]: info: ------------------------------ 40ms Apr 19 15:03:37 volumio-joe volumio[1029]: info: ------------------------------ 38ms Apr 19 15:03:39 volumio-joe volumiologrotate[594]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 19 15:03:39 volumio-joe volumiologrotate[594]: ls: cannot access 'JOE': No such file or directory Apr 19 15:03:40 volumio-joe wpa_supplicant[879]: wlan0: Failed to initiate sched scan Apr 19 15:03:47 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Apr 19 15:03:47 volumio-joe volumio[1029]: info: Saving new wireless network Apr 19 15:03:47 volumio-joe sudo[2707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Apr 19 15:03:47 volumio-joe sudo[2707]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:47 volumio-joe sudo[2707]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:47 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 19 15:03:47 volumio-joe volumio[1029]: info: Discovery: Restarting Advertising due to device name change Apr 19 15:03:47 volumio-joe sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 19 15:03:47 volumio-joe sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:47 volumio-joe systemd[1]: wireless.service: Killing process 879 (wpa_supplicant) with signal SIGKILL. Apr 19 15:03:47 volumio-joe systemd[1]: wireless.service: Killing process 979 (dhcpcd) with signal SIGKILL. Apr 19 15:03:47 volumio-joe systemd[1]: Stopping Wireless Services... Apr 19 15:03:47 volumio-joe kernel: ieee80211 phy0: brcmf_proto_bcdc_query_dcmd: brcmf_proto_bcdc_msg failed w/status -110 Apr 19 15:03:47 volumio-joe kernel: brcmfmac: brcmf_dump_obss: dump_obss error (-110) Apr 19 15:03:47 volumio-joe kernel: ieee80211 phy0: brcmf_set_mpc: fail to set mpc Apr 19 15:03:48 volumio-joe systemd[1]: wireless.service: Succeeded. Apr 19 15:03:48 volumio-joe systemd[1]: Stopped Wireless Services. Apr 19 15:03:48 volumio-joe systemd[1]: Starting Wireless Services... Apr 19 15:03:48 volumio-joe wireless.js[2712]: Cleaning previous... Apr 19 15:03:48 volumio-joe sudo[2728]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 19 15:03:48 volumio-joe sudo[2728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:48 volumio-joe sudo[2728]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:48 volumio-joe sudo[2730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 19 15:03:48 volumio-joe sudo[2730]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:48 volumio-joe volumio[1029]: info: Discovery: A device disappeared from network Apr 19 15:03:48 volumio-joe volumio[1029]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 19 15:03:49 volumio-joe sudo[2730]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:49 volumio-joe wireless.js[2712]: Stopped aP Apr 19 15:03:49 volumio-joe sudo[2737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 19 15:03:49 volumio-joe sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:49 volumio-joe sudo[2737]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:49 volumio-joe kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 19 15:03:49 volumio-joe sudo[2739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 19 15:03:49 volumio-joe sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:49 volumio-joe sudo[2739]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:49 volumio-joe sudo[2746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 19 15:03:49 volumio-joe sudo[2746]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:49 volumio-joe sudo[2746]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:49 volumio-joe sudo[2750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 19 15:03:49 volumio-joe sudo[2750]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:50 volumio-joe sudo[2750]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:50 volumio-joe wireless.js[2712]: SETTING APPROPRIATE REG DOMAIN: CN Apr 19 15:03:50 volumio-joe sudo[2755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 19 15:03:50 volumio-joe sudo[2755]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:50 volumio-joe sudo[2755]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:50 volumio-joe sudo[2757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Apr 19 15:03:50 volumio-joe sudo[2757]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:50 volumio-joe sudo[2757]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:50 volumio-joe wireless.js[2712]: SUCCESSFULLY SET NEW REGDOMAIN: CN Apr 19 15:03:50 volumio-joe wireless.js[2712]: Start wireless flow Apr 19 15:03:50 volumio-joe wireless.js[2712]: Stopped hotspot (if there).. Apr 19 15:03:50 volumio-joe sudo[2764]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 19 15:03:50 volumio-joe sudo[2764]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:50 volumio-joe sudo[2764]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:50 volumio-joe sudo[2766]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 19 15:03:50 volumio-joe sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:50 volumio-joe sudo[2766]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:50 volumio-joe wireless.js[2712]: DHCP IP Apr 19 15:03:50 volumio-joe wpa_supplicant[2776]: Successfully initialized wpa_supplicant Apr 19 15:03:50 volumio-joe wireless.js[2712]: Start ap Apr 19 15:03:50 volumio-joe sudo[2781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 19 15:03:50 volumio-joe sudo[2781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:50 volumio-joe dhcpcd[2784]: main: control_open: Connection refused Apr 19 15:03:50 volumio-joe dhcpcd[2784]: dev: loaded udev Apr 19 15:03:50 volumio-joe kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 19 15:03:50 volumio-joe dhcpcd-run-hooks[2793]: wlan0: starting wpa_supplicant Apr 19 15:03:51 volumio-joe dhcpcd-run-hooks[2798]: wlan0: failed to start wpa_supplicant Apr 19 15:03:51 volumio-joe dhcpcd-run-hooks[2799]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Apr 19 15:03:51 volumio-joe dhcpcd[2784]: wlan0: connected to Access Point `' Apr 19 15:03:51 volumio-joe dhcpcd[2784]: DUID 00:01:00:01:2f:7d:86:a5:e4:5f:01:d2:2b:f6 Apr 19 15:03:51 volumio-joe dhcpcd[2784]: eth0: IAID 01:d2:2b:f6 Apr 19 15:03:51 volumio-joe dhcpcd[2784]: eth0: adding address fe80::5c80:975c:c950:40af Apr 19 15:03:51 volumio-joe dhcpcd[2784]: ipv6_addaddr1: Permission denied Apr 19 15:03:51 volumio-joe dhcpcd[2784]: wlan0: waiting for carrier Apr 19 15:03:51 volumio-joe dhcpcd[2784]: eth0: soliciting an IPv6 router Apr 19 15:03:51 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:51 volumio-joe sudo[2820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:51 volumio-joe sudo[2820]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:51 volumio-joe sudo[2820]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:52 volumio-joe dhcpcd[2784]: eth0: rebinding lease of 192.168.8.103 Apr 19 15:03:52 volumio-joe dhcpcd[2784]: eth0: leased 192.168.8.103 for 3600 seconds Apr 19 15:03:52 volumio-joe dhcpcd[2784]: eth0: adding route to 192.168.8.0/24 Apr 19 15:03:52 volumio-joe dhcpcd[2784]: eth0: adding default route via 192.168.8.1 Apr 19 15:03:52 volumio-joe dhcpcd[2784]: forked to background, child pid 2844 Apr 19 15:03:52 volumio-joe sudo[2781]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:52 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:52 volumio-joe sudo[2846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:52 volumio-joe sudo[2846]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:52 volumio-joe sudo[2846]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:52 volumio-joe volumio[1029]: info: Discovery: Started advertising with name: Volumio joe Apr 19 15:03:53 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:53 volumio-joe sudo[2852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:53 volumio-joe sudo[2852]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:53 volumio-joe sudo[2852]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:53 volumio-joe volumio[1029]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Apr 19 15:03:54 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:54 volumio-joe sudo[2858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:54 volumio-joe sudo[2858]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:54 volumio-joe sudo[2858]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:55 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:55 volumio-joe sudo[2863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:55 volumio-joe sudo[2863]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:55 volumio-joe sudo[2863]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:56 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:56 volumio-joe sudo[2868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:56 volumio-joe sudo[2868]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:56 volumio-joe sudo[2868]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:57 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:57 volumio-joe sudo[2873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:57 volumio-joe sudo[2873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:57 volumio-joe sudo[2873]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:58 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:58 volumio-joe sudo[2878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:58 volumio-joe sudo[2878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:58 volumio-joe sudo[2878]: pam_unix(sudo:session): session closed for user root Apr 19 15:03:59 volumio-joe wireless.js[2712]: trying... Apr 19 15:03:59 volumio-joe sudo[2883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:03:59 volumio-joe sudo[2883]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:03:59 volumio-joe sudo[2883]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:00 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:04:00 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:00 volumio-joe sudo[2895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:00 volumio-joe sudo[2895]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:00 volumio-joe sudo[2895]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:01 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:01 volumio-joe sudo[2907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:01 volumio-joe sudo[2907]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:01 volumio-joe sudo[2907]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:02 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:02 volumio-joe sudo[2915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:02 volumio-joe sudo[2915]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:02 volumio-joe sudo[2915]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:02 volumio-joe nmbd[729]: [2025/04/19 15:04:02.984698, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 19 15:04:02 volumio-joe nmbd[729]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Apr 19 15:04:02 volumio-joe nmbd[729]: [2025/04/19 15:04:02.984854, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 19 15:04:02 volumio-joe nmbd[729]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Apr 19 15:04:03 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:03 volumio-joe sudo[2921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:03 volumio-joe sudo[2921]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:03 volumio-joe sudo[2921]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:04 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:04 volumio-joe sudo[2926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:04 volumio-joe sudo[2926]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:04 volumio-joe sudo[2926]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:05 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:05 volumio-joe sudo[2931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:05 volumio-joe sudo[2931]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:05 volumio-joe sudo[2931]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:06 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:06 volumio-joe sudo[2936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:06 volumio-joe sudo[2936]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:06 volumio-joe sudo[2936]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:07 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:07 volumio-joe sudo[2941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:07 volumio-joe sudo[2941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:07 volumio-joe sudo[2941]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:08 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetQueue Apr 19 15:04:08 volumio-joe volumio[1029]: info: CoreStateMachine::getQueue Apr 19 15:04:08 volumio-joe volumio[1029]: info: CorePlayQueue::getQueue Apr 19 15:04:08 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:08 volumio-joe sudo[2946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:08 volumio-joe sudo[2946]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:08 volumio-joe sudo[2946]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:09 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:09 volumio-joe sudo[2951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:09 volumio-joe sudo[2951]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:09 volumio-joe sudo[2951]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:10 volumio-joe wireless.js[2712]: trying... Apr 19 15:04:10 volumio-joe sudo[2956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 19 15:04:10 volumio-joe sudo[2956]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:10 volumio-joe sudo[2956]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:11 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 19 15:04:11 volumio-joe volumio[1029]: info: CURURI: music-library Apr 19 15:04:11 volumio-joe volumio[1029]: error: Failed LSINFO: null Apr 19 15:04:11 volumio-joe volumio[1029]: info: Preload queue cleared Apr 19 15:04:11 volumio-joe wpa_supplicant[2797]: wlan0: Failed to initiate sched scan Apr 19 15:04:11 volumio-joe wireless.js[2712]: Overtime, starting plan B Apr 19 15:04:11 volumio-joe kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 19 15:04:11 volumio-joe systemd[1]: Started Wireless Services. Apr 19 15:04:11 volumio-joe sudo[2710]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 19 15:04:16 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 19 15:04:16 volumio-joe sudo[2988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:04:16 volumio-joe sudo[2988]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:16 volumio-joe sudo[2988]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:17 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetState Apr 19 15:04:19 volumio-joe wpa_supplicant[2797]: wlan0: Failed to initiate sched scan Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:04:20 volumio-joe volumio[1029]: info: Retrieving Cloud Streaming UI Apr 19 15:04:20 volumio-joe volumio[1029]: info: Getting Tidal Cloud Configuration Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:04:20 volumio-joe volumio[1029]: info: Getting Qobuz Cloud Configuration Apr 19 15:04:20 volumio-joe volumio[1029]: info: Asking plugin for UI Config Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:04:20 volumio-joe volumio[1029]: info: Getting Spotify Cloud Configuration Apr 19 15:04:20 volumio-joe volumio[1029]: info: Asking plugin for UI Config Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:04:20 volumio-joe volumio[1029]: info: Saving Spotify Acccount Apr 19 15:04:20 volumio-joe volumio[1029]: info: Got Tidal Cloud Configuration Apr 19 15:04:20 volumio-joe volumio[1029]: info: Got it Apr 19 15:04:20 volumio-joe volumio[1029]: info: Got it Apr 19 15:04:20 volumio-joe volumio[1029]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 19 15:04:20 volumio-joe volumio[1029]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 19 15:04:20 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::volumioGetBrowseSources Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Apr 19 15:04:21 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Apr 19 15:04:21 volumio-joe volumio[1029]: info: Refreshing Cached IP Addresses Apr 19 15:04:21 volumio-joe sudo[3015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 19 15:04:21 volumio-joe sudo[3015]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:21 volumio-joe sudo[3015]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:21 volumio-joe sudo[3018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:04:21 volumio-joe sudo[3018]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:21 volumio-joe sudo[3018]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:21 volumio-joe sudo[3020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:04:21 volumio-joe sudo[3020]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:21 volumio-joe systemd[1]: upmpdcli.service: Succeeded. Apr 19 15:04:21 volumio-joe sudo[3020]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:25 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 15:04:27 volumio-joe wpa_supplicant[2797]: wlan0: Failed to initiate sched scan Apr 19 15:04:29 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 15:04:31 volumio-joe sudo[3042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 19 15:04:31 volumio-joe sudo[3042]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:31 volumio-joe sudo[3042]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:31 volumio-joe sudo[3044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 19 15:04:31 volumio-joe sudo[3044]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:32 volumio-joe sudo[3044]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:32 volumio-joe sudo[3048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 19 15:04:32 volumio-joe sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 19 15:04:32 volumio-joe systemd[1]: Started UPnP Renderer front-end to MPD. Apr 19 15:04:32 volumio-joe sudo[3048]: pam_unix(sudo:session): session closed for user root Apr 19 15:04:32 volumio-joe volumio[1029]: info: Upmpdcli Daemon Started Apr 19 15:04:32 volumio-joe volumio[3050]: writing RSA key Apr 19 15:04:33 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 15:04:36 volumio-joe wpa_supplicant[2797]: wlan0: Failed to initiate sched scan Apr 19 15:04:37 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 15:04:39 volumio-joe volumiologrotate[594]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 19 15:04:39 volumio-joe volumiologrotate[594]: ls: cannot access 'JOE': No such file or directory Apr 19 15:04:41 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 19 15:04:41 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 19 15:04:41 volumio-joe volumio[1029]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 19 15:04:41 volumio-joe volumio[1029]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 15:04:41 volumio-joe volumio[1029]: Error: getaddrinfo -3007 Apr 19 15:04:41 volumio-joe volumio[1029]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 19 15:04:41 volumio-joe volumio[1029]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 19 15:04:41 volumio-joe volumio[1029]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 19 15:04:41 volumio-joe volumio[1029]: code: -3007, Apr 19 15:04:41 volumio-joe volumio[1029]: errno: -3007, Apr 19 15:04:41 volumio-joe volumio[1029]: syscall: 'getaddrinfo' Apr 19 15:04:41 volumio-joe volumio[1029]: } Apr 19 15:04:41 volumio-joe volumio[1029]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 15:04:42 volumio-joe sudo[3167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 15:03 Apr 19 15:04:42 volumio-joe sudo[3167]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"