Jan 08 21:31:06 ezzimertest volumio[1014]: info: MyVolumio login type: Token Jan 08 21:31:06 ezzimertest volumio[1014]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 21:31:06 ezzimertest volumio[1014]: info: Retrying Login Due to Network Error Jan 08 21:31:16 ezzimertest volumio[1014]: info: MyVolumio login type: Token Jan 08 21:31:16 ezzimertest volumio[1014]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 21:31:16 ezzimertest volumio[1014]: info: Retrying Login Due to Network Error Jan 08 21:31:26 ezzimertest volumio[1014]: info: MyVolumio login type: Token Jan 08 21:31:26 ezzimertest volumio[1014]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 21:31:26 ezzimertest volumio[1014]: info: Retrying Login Due to Network Error Jan 08 21:31:27 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: webradio , addMyWebRadio Jan 08 21:31:27 ezzimertest volumio[1014]: info: {"name":"bob netz","uri":"http://streams.radiobob.de/bob-live/mp3-192/mediaplayer"} Jan 08 21:31:27 ezzimertest volumio[1014]: info: Saving local item my-web-radio Jan 08 21:31:32 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioGetState Jan 08 21:31:34 ezzimertest volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 21:31:34 ezzimertest volumio[1014]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Jan 08 21:31:34 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 08 21:31:34 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 08 21:31:34 ezzimertest sudo[2360]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 08 21:31:34 ezzimertest sudo[2360]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 21:31:34 ezzimertest sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 21:31:34 ezzimertest sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 08 21:31:34 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 08 21:31:34 ezzimertest sudo[2360]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest sudo[2377]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 21:31:34 ezzimertest sudo[2377]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 21:31:34 ezzimertest sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest sudo[2365]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest sudo[2387]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 21:31:34 ezzimertest sudo[2371]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest sudo[2381]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest sudo[2377]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest sudo[2395]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 08 21:31:34 ezzimertest sudo[2395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest sudo[2387]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest sudo[2395]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest volumio[1014]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 08 21:31:34 ezzimertest volumio[1014]: wlan0 Interface doesn't support scanning. Jan 08 21:31:34 ezzimertest volumio[1014]: info: Cannot use regular scanning, forcing with ap-force Jan 08 21:31:34 ezzimertest sudo[2412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 08 21:31:34 ezzimertest sudo[2412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:34 ezzimertest sudo[2412]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:34 ezzimertest volumio[1014]: command failed: No such device (-19) Jan 08 21:31:34 ezzimertest volumio[1014]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 08 21:31:34 ezzimertest volumio[1014]: command failed: No such device (-19) Jan 08 21:31:36 ezzimertest volumio[1014]: info: MyVolumio login type: Token Jan 08 21:31:36 ezzimertest volumio[1014]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 21:31:36 ezzimertest volumio[1014]: info: Retrying Login Due to Network Error Jan 08 21:31:46 ezzimertest volumio[1014]: info: MyVolumio login type: Token Jan 08 21:31:46 ezzimertest volumio[1014]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 21:31:46 ezzimertest volumio[1014]: info: Retrying Login Due to Network Error Jan 08 21:31:46 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioGetState Jan 08 21:31:47 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPlay Jan 08 21:31:47 ezzimertest volumio[1014]: info: CoreStateMachine::play index undefined Jan 08 21:31:47 ezzimertest volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 21:31:47 ezzimertest volumio[1014]: info: CorePlayQueue::getTrack 0 Jan 08 21:31:47 ezzimertest volumio[1014]: info: CoreStateMachine::startPlaybackTimer Jan 08 21:31:47 ezzimertest volumio[1014]: info: CorePlayQueue::getTrack 0 Jan 08 21:31:47 ezzimertest volumio[1014]: info: [1767904307987] ControllerWebradio::clearAddPlayTrack Jan 08 21:31:47 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 21:31:47 ezzimertest volumio[1014]: info: sendMpdCommand stop took 2 milliseconds Jan 08 21:31:47 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 21:31:47 ezzimertest volumio[1014]: info: Jan 08 21:31:47 ezzimertest volumio[1014]: ---------------------------- MPD announces system playlist update Jan 08 21:31:47 ezzimertest volumio[1014]: info: Ignoring MPD Status Update Jan 08 21:31:47 ezzimertest volumio[1014]: info: sendMpdCommand clear took 3 milliseconds Jan 08 21:31:47 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://streams.radiobob.de/bob-live/mp3-192/mediaplayer" Jan 08 21:31:47 ezzimertest volumio[1014]: info: Jan 08 21:31:47 ezzimertest volumio[1014]: ---------------------------- MPD announces system playlist update Jan 08 21:31:47 ezzimertest volumio[1014]: info: Ignoring MPD Status Update Jan 08 21:31:48 ezzimertest volumio[1014]: error: updateQueue error: null Jan 08 21:31:48 ezzimertest volumio[1014]: info: ------------------------------ 9ms Jan 08 21:31:48 ezzimertest volumio[1014]: error: updateQueue error: null Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand add "http://streams.radiobob.de/bob-live/mp3-192/mediaplayer" Jan 08 21:31:48 ezzimertest volumio[1014]: info: ------------------------------ 9ms Jan 08 21:31:48 ezzimertest volumio[1014]: info: Jan 08 21:31:48 ezzimertest volumio[1014]: ---------------------------- MPD announces system playlist update Jan 08 21:31:48 ezzimertest volumio[1014]: info: Ignoring MPD Status Update Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand add "http://streams.radiobob.de/bob-live/mp3-192/mediaplayer" took 7 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand play Jan 08 21:31:48 ezzimertest volumio[1014]: info: ------------------------------ 8ms Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand play took 14 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: info: Jan 08 21:31:48 ezzimertest volumio[1014]: ---------------------------- MPD announces system playlist update Jan 08 21:31:48 ezzimertest volumio[1014]: info: Ignoring MPD Status Update Jan 08 21:31:48 ezzimertest volumio[1014]: info: Jan 08 21:31:48 ezzimertest volumio[1014]: ---------------------------- MPD announces state update: player Jan 08 21:31:48 ezzimertest volumio[1014]: info: Jan 08 21:31:48 ezzimertest volumio[1014]: ---------------------------- MPD announces state update: player Jan 08 21:31:48 ezzimertest volumio[1014]: info: ControllerMpd::getState Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jan 08 21:31:48 ezzimertest volumio[1014]: info: ------------------------------ 7ms Jan 08 21:31:48 ezzimertest volumio[1014]: info: ControllerMpd::getState Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jan 08 21:31:48 ezzimertest volumio[1014]: info: Jan 08 21:31:48 ezzimertest volumio[1014]: ---------------------------- MPD announces state update: player Jan 08 21:31:48 ezzimertest volumio[1014]: info: ControllerMpd::getState Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand status took 18 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand status took 18 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand status took 17 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::parseState Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::parseState Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::parseState Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand clearerror took 26 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand clearerror took 25 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand clearerror took 22 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::parseTrackInfo Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::parseTrackInfo Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: ControllerMpd::parseTrackInfo Jan 08 21:31:48 ezzimertest volumio[1014]: info: ControllerMpd::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::servicePushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CorePlayQueue::getTrack 0 Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mediaplayer","artist":null,"album":null,"uri":"http://streams.radiobob.de/bob-live/mp3-192/mediaplayer","trackType":""} Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: CURRENT POSITION 0 Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::syncState stateService stop Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: No code Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: ControllerMpd::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::servicePushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CorePlayQueue::getTrack 0 Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mediaplayer","artist":null,"album":null,"uri":"http://streams.radiobob.de/bob-live/mp3-192/mediaplayer","trackType":""} Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: CURRENT POSITION 0 Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::syncState stateService stop Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: No code Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: ControllerMpd::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::servicePushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CorePlayQueue::getTrack 0 Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mediaplayer","artist":null,"album":null,"uri":"http://streams.radiobob.de/bob-live/mp3-192/mediaplayer","trackType":""} Jan 08 21:31:48 ezzimertest volumio[1014]: verbose: CURRENT POSITION 0 Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::syncState stateService stop Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: No code Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreStateMachine::pushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioPushState Jan 08 21:31:48 ezzimertest volumio[1014]: info: ------------------------------ 226ms Jan 08 21:31:48 ezzimertest volumio[1014]: info: ------------------------------ 227ms Jan 08 21:31:48 ezzimertest volumio[1014]: info: ------------------------------ 224ms Jan 08 21:31:56 ezzimertest volumio[1014]: info: MyVolumio login type: Token Jan 08 21:31:56 ezzimertest volumio[1014]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 21:31:56 ezzimertest volumio[1014]: info: Retrying Login Due to Network Error Jan 08 21:31:56 ezzimertest volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 21:31:56 ezzimertest volumio[1014]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Jan 08 21:31:56 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 08 21:31:56 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 08 21:31:56 ezzimertest sudo[2472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 08 21:31:56 ezzimertest sudo[2472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:56 ezzimertest sudo[2477]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 21:31:56 ezzimertest sudo[2477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:56 ezzimertest sudo[2472]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:56 ezzimertest sudo[2483]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 21:31:56 ezzimertest sudo[2483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:56 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 08 21:31:56 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 08 21:31:56 ezzimertest sudo[2494]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 21:31:56 ezzimertest sudo[2488]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 08 21:31:56 ezzimertest sudo[2488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:56 ezzimertest sudo[2494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:56 ezzimertest sudo[2494]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:56 ezzimertest sudo[2500]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 21:31:56 ezzimertest sudo[2477]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:56 ezzimertest sudo[2500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:56 ezzimertest sudo[2483]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:56 ezzimertest sudo[2510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 08 21:31:56 ezzimertest sudo[2510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:56 ezzimertest sudo[2500]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:56 ezzimertest sudo[2488]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:57 ezzimertest sudo[2510]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:57 ezzimertest volumio[1014]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jan 08 21:31:57 ezzimertest volumio[1014]: wlan0 Interface doesn't support scanning. Jan 08 21:31:57 ezzimertest volumio[1014]: info: Cannot use regular scanning, forcing with ap-force Jan 08 21:31:57 ezzimertest sudo[2524]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jan 08 21:31:57 ezzimertest sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:31:57 ezzimertest sudo[2524]: pam_unix(sudo:session): session closed for user root Jan 08 21:31:57 ezzimertest volumio[1014]: command failed: No such device (-19) Jan 08 21:31:57 ezzimertest volumio[1014]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jan 08 21:31:57 ezzimertest volumio[1014]: command failed: No such device (-19) Jan 08 21:32:01 ezzimertest volumio[1014]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Jan 08 21:32:01 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Jan 08 21:32:01 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 08 21:32:01 ezzimertest volumio[1014]: info: Discovery: Restarting Advertising due to device name change Jan 08 21:32:01 ezzimertest volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 08 21:32:01 ezzimertest volumio[1014]: info: Discovery: Restarting Advertising due to device name change Jan 08 21:32:01 ezzimertest sudo[2529]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/resolv.conf.tail Jan 08 21:32:01 ezzimertest sudo[2529]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:32:01 ezzimertest sudo[2529]: pam_unix(sudo:session): session closed for user root Jan 08 21:32:01 ezzimertest sudo[2531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 08 21:32:01 ezzimertest sudo[2531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:32:01 ezzimertest sudo[2533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jan 08 21:32:01 ezzimertest sudo[2533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:32:01 ezzimertest avahi-daemon[580]: Withdrawing address record for 192.168.2.78 on eth0. Jan 08 21:32:01 ezzimertest dhcpcd[618]: eth0: pid 2536 deleted IP address 192.168.2.78/24 Jan 08 21:32:01 ezzimertest dhcpcd[664]: eth0: pid 2536 deleted IP address 192.168.2.78/24 Jan 08 21:32:01 ezzimertest dhcpcd[618]: eth0: deleting route to 192.168.2.0/24 Jan 08 21:32:01 ezzimertest dhcpcd[664]: eth0: deleting route to 192.168.2.0/24 Jan 08 21:32:01 ezzimertest avahi-daemon[580]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.2.78. Jan 08 21:32:01 ezzimertest avahi-daemon[580]: Interface eth0.IPv4 no longer relevant for mDNS. Jan 08 21:32:01 ezzimertest dhcpcd[664]: eth0: deleting default route via 192.168.2.1 Jan 08 21:32:01 ezzimertest dhcpcd[618]: eth0: deleting default route via 192.168.2.1 Jan 08 21:32:01 ezzimertest sudo[2533]: pam_unix(sudo:session): session closed for user root Jan 08 21:32:01 ezzimertest volumio[1014]: info: Discovery: A device disappeared from network Jan 08 21:32:01 ezzimertest dhcpcd[664]: eth0: rebinding lease of 192.168.2.78 Jan 08 21:32:01 ezzimertest dhcpcd[618]: eth0: rebinding lease of 192.168.2.78 Jan 08 21:32:01 ezzimertest sudo[2547]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jan 08 21:32:01 ezzimertest sudo[2547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:32:01 ezzimertest kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jan 08 21:32:01 ezzimertest kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 08 21:32:01 ezzimertest dhcpcd[618]: eth0: carrier lost Jan 08 21:32:01 ezzimertest dhcpcd[664]: eth0: carrier lost Jan 08 21:32:01 ezzimertest systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 08 21:32:01 ezzimertest sudo[2547]: pam_unix(sudo:session): session closed for user root Jan 08 21:32:01 ezzimertest systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 08 21:32:01 ezzimertest systemd[1]: welcome.service: Deactivated successfully. Jan 08 21:32:01 ezzimertest systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 08 21:32:01 ezzimertest systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 08 21:32:01 ezzimertest dhcpcd[2557]: ps_bpf_recvmsg: Network is down Jan 08 21:32:01 ezzimertest dhcpcd[2556]: ps_bpf_recvmsg: Network is down Jan 08 21:32:01 ezzimertest sudo[2561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jan 08 21:32:01 ezzimertest sudo[2561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:32:01 ezzimertest systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 08 21:32:01 ezzimertest systemd[1]: Starting wireless.service - Wireless Services... Jan 08 21:32:01 ezzimertest ifplugd(eth0)[826]: Link beat lost. Jan 08 21:32:01 ezzimertest kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 08 21:32:01 ezzimertest kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Jan 08 21:32:01 ezzimertest welcome[2565]: Resolved ip:[0] Jan 08 21:32:01 ezzimertest sudo[2561]: pam_unix(sudo:session): session closed for user root Jan 08 21:32:01 ezzimertest systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 08 21:32:01 ezzimertest systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 08 21:32:02 ezzimertest volumio[1014]: info: Discovery: A device disappeared from network Jan 08 21:32:03 ezzimertest wireless.js[2585]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Jan 08 21:32:03 ezzimertest wireless.js[2585]: WIRELESS.JS: Wired network status changed to: ---connected--- Jan 08 21:32:03 ezzimertest wireless.js[2585]: WIRELESS.JS: Wireless.js initializing wireless flow Jan 08 21:32:03 ezzimertest wireless.js[2585]: WIRELESS.JS: Cleaning previous... Jan 08 21:32:03 ezzimertest wireless.js[2585]: WIRELESS.JS: No wireless interface, exiting Jan 08 21:32:03 ezzimertest dhcpcd[664]: eth0: carrier acquired Jan 08 21:32:03 ezzimertest kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Jan 08 21:32:03 ezzimertest dhcpcd[618]: eth0: carrier acquired Jan 08 21:32:03 ezzimertest dhcpcd[618]: eth0: IAID eb:ca:d0:d8 Jan 08 21:32:03 ezzimertest dhcpcd[664]: eth0: IAID eb:ca:d0:d8 Jan 08 21:32:03 ezzimertest systemd[1]: wireless.service: Killing process 2614 (sh) with signal SIGKILL. Jan 08 21:32:03 ezzimertest systemd[1]: wireless.service: Killing process 2615 (systemctl) with signal SIGKILL. Jan 08 21:32:03 ezzimertest systemd[1]: wireless.service: Failed with result 'protocol'. Jan 08 21:32:03 ezzimertest systemd[1]: Failed to start wireless.service - Wireless Services. Jan 08 21:32:03 ezzimertest systemd[1]: wireless.service: Consumed 1.703s CPU time. Jan 08 21:32:03 ezzimertest sudo[2531]: pam_unix(sudo:session): session closed for user root Jan 08 21:32:03 ezzimertest dhcpcd[664]: eth0: rebinding lease of 192.168.2.78 Jan 08 21:32:03 ezzimertest dhcpcd[664]: eth0: probing address 192.168.2.78/24 Jan 08 21:32:03 ezzimertest dhcpcd[618]: eth0: soliciting an IPv6 router Jan 08 21:32:03 ezzimertest ifplugd(eth0)[826]: Link beat detected. Jan 08 21:32:04 ezzimertest dhcpcd[664]: eth0: soliciting an IPv6 router Jan 08 21:32:04 ezzimertest ntpd[819]: IO: Deleting interface #4 eth0, 192.168.2.78#123, interface stats: received=109, sent=109, dropped=0, active_time=319 secs Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 78.47.56.71 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 5.189.151.39 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 128.140.37.196 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 139.162.152.20 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 172.104.134.72 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 78.47.249.55 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 178.63.248.241 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 162.159.200.123 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 185.104.163.42 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 185.252.140.126 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 128.140.109.119 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 88.198.7.62 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 202.61.251.102 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 217.91.44.17 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 51.75.67.47 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest ntpd[819]: PROTO: 116.203.244.102 unlink local addr 192.168.2.78 -> Jan 08 21:32:04 ezzimertest dhcpcd[618]: eth0: rebinding lease of 192.168.2.78 Jan 08 21:32:04 ezzimertest dhcpcd[618]: eth0: probing address 192.168.2.78/24 Jan 08 21:32:06 ezzimertest volumio[1014]: info: Discovery: Started advertising with name: Ezzimertest Jan 08 21:32:06 ezzimertest volumio[1014]: info: MyVolumio login type: Token Jan 08 21:32:06 ezzimertest volumio[1014]: info: Discovery: Started advertising with name: Ezzimertest Jan 08 21:32:06 ezzimertest volumio[1014]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 21:32:06 ezzimertest volumio[1014]: info: Retrying Login Due to Network Error Jan 08 21:32:06 ezzimertest volumio[1014]: info: Volumio Network Manager: Network status updated: 0 Jan 08 21:32:07 ezzimertest volumio[1014]: info: Discovery: adding 76eba1a1-9f87-4085-a851-7ad3348403ab Jan 08 21:32:07 ezzimertest volumio[1014]: info: Discovery: Found device Ezzimertest Jan 08 21:32:07 ezzimertest volumio[1014]: info: CoreCommandRouter::volumioGetState Jan 08 21:32:08 ezzimertest dhcpcd[664]: eth0: leased 192.168.2.78 for 86400 seconds Jan 08 21:32:08 ezzimertest avahi-daemon[580]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.78. Jan 08 21:32:08 ezzimertest avahi-daemon[580]: New relevant interface eth0.IPv4 for mDNS. Jan 08 21:32:08 ezzimertest avahi-daemon[580]: Registering new address record for 192.168.2.78 on eth0.IPv4. Jan 08 21:32:08 ezzimertest dhcpcd[664]: eth0: adding route to 192.168.2.0/24 Jan 08 21:32:08 ezzimertest dhcpcd[664]: eth0: adding default route via 192.168.2.1 Jan 08 21:32:08 ezzimertest systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jan 08 21:32:08 ezzimertest systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jan 08 21:32:08 ezzimertest systemd[1]: welcome.service: Deactivated successfully. Jan 08 21:32:08 ezzimertest systemd[1]: Stopped welcome.service - Show a welcome message on console. Jan 08 21:32:08 ezzimertest systemd[1]: Stopping welcome.service - Show a welcome message on console... Jan 08 21:32:08 ezzimertest systemd[1]: Starting welcome.service - Show a welcome message on console... Jan 08 21:32:08 ezzimertest welcome[2662]: Resolved ip:[1] 192.168.2.78 Jan 08 21:32:08 ezzimertest volumio[1014]: compat.c: read() failed: Resource temporarily unavailable Jan 08 21:32:08 ezzimertest volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 21:32:08 ezzimertest volumio[1014]: Error: dns service error: unknown Jan 08 21:32:08 ezzimertest volumio[1014]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 08 21:32:08 ezzimertest volumio[1014]: errorCode: -65537 Jan 08 21:32:08 ezzimertest volumio[1014]: } Jan 08 21:32:08 ezzimertest systemd[1]: Finished welcome.service - Show a welcome message on console. Jan 08 21:32:08 ezzimertest volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 21:32:08 ezzimertest systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jan 08 21:32:10 ezzimertest ntpd[819]: IO: Listen normally on 5 eth0 192.168.2.78:123 Jan 08 21:32:10 ezzimertest ntpd[819]: IO: new interface(s) found: waking up resolver Jan 08 21:32:10 ezzimertest sudo[2686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-08 21:31' Jan 08 21:32:10 ezzimertest sudo[2686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 21:32:10 ezzimertest dhcpcd[618]: eth0: leased 192.168.2.78 for 86400 seconds Jan 08 21:32:10 ezzimertest dhcpcd[618]: eth0: adding route to 192.168.2.0/24 Jan 08 21:32:10 ezzimertest dhcpcd[618]: eth0: adding default route via 192.168.2.1 PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"