Dec 29 11:48:05 volumio volumio[1372]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Dec 29 11:48:05 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 29 11:48:06 volumio sudo[3408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 29 11:48:06 volumio sudo[3408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:06 volumio sudo[3408]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:06 volumio volumio[1372]: info: Permissions for /etc/hostapd/hostapd.conf Dec 29 11:48:06 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 29 11:48:06 volumio volumio[1372]: info: Discovery: Restarting Advertising due to device name change Dec 29 11:48:06 volumio sudo[3411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 29 11:48:06 volumio sudo[3411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:06 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 29 11:48:06 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 29 11:48:06 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 29 11:48:06 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 29 11:48:07 volumio wireless.js[3413]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 29 11:48:07 volumio wireless.js[3413]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 29 11:48:07 volumio wireless.js[3413]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 29 11:48:07 volumio wireless.js[3413]: WIRELESS.JS: Cleaning previous... Dec 29 11:48:07 volumio sudo[3431]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 29 11:48:07 volumio sudo[3431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 29 11:48:07 volumio sudo[3431]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:07 volumio sudo[3433]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 29 11:48:07 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 29 11:48:07 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:07 volumio wireless.js[3413]: WIRELESS.JS: Stopped aP Dec 29 11:48:07 volumio sudo[3441]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 29 11:48:07 volumio sudo[3441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:07 volumio sudo[3441]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:07 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 29 11:48:07 volumio sudo[3443]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 29 11:48:07 volumio sudo[3443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:07 volumio sudo[3443]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:07 volumio sudo[3451]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 29 11:48:07 volumio sudo[3451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:07 volumio sudo[3451]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:07 volumio sudo[3453]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 29 11:48:07 volumio sudo[3453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:07 volumio volumio[1372]: info: Discovery: A device disappeared from network Dec 29 11:48:07 volumio volumio[1372]: info: Discovery: Device volumio disappeared from network Dec 29 11:48:07 volumio volumio[1372]: info: Discovery: A device disappeared from network Dec 29 11:48:09 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Dec 29 11:48:09 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:48:10 volumio sudo[3453]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:10 volumio wireless.js[3413]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: FR Dec 29 11:48:10 volumio sudo[3473]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 29 11:48:10 volumio sudo[3473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:10 volumio sudo[3473]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:10 volumio sudo[3475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Dec 29 11:48:10 volumio sudo[3475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:10 volumio sudo[3475]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:10 volumio wireless.js[3413]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: FR Dec 29 11:48:10 volumio wireless.js[3413]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 29 11:48:10 volumio wireless.js[3413]: WIRELESS.JS: Notified systemd about wireless ready Dec 29 11:48:10 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 29 11:48:10 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 29 11:48:10 volumio sudo[3411]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:11 volumio volumio[1372]: info: Discovery: Started advertising with name: Volumio Dec 29 11:48:12 volumio volumio[1372]: info: Discovery: adding cc9bdfec-99b2-4d0d-baa8-97ae2f231662 Dec 29 11:48:12 volumio volumio[1372]: info: Discovery: Found device Volumio Dec 29 11:48:12 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Dec 29 11:48:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:48:12 volumio volumio[1372]: info: Discovery: this is already registered, cc9bdfec-99b2-4d0d-baa8-97ae2f231662 Dec 29 11:48:12 volumio volumio[1372]: info: Discovery: Found device Volumio Dec 29 11:48:12 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Dec 29 11:48:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:48:15 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Dec 29 11:48:15 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Dec 29 11:48:15 volumio sudo[3492]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 11:48:15 volumio sudo[3492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:15 volumio sudo[3492]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:17 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Dec 29 11:48:17 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:48:20 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Dec 29 11:48:20 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Dec 29 11:48:20 volumio volumio[1372]: info: Refreshing Cached IP Addresses Dec 29 11:48:20 volumio sudo[3512]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Dec 29 11:48:20 volumio sudo[3512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:20 volumio sudo[3514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 29 11:48:20 volumio sudo[3514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:20 volumio sudo[3514]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:20 volumio sudo[3512]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:20 volumio sudo[3516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 11:48:20 volumio sudo[3516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:20 volumio sudo[3516]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:30 volumio sudo[3536]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 29 11:48:30 volumio sudo[3536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:30 volumio sudo[3538]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 11:48:30 volumio sudo[3536]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:30 volumio sudo[3538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:30 volumio sudo[3542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 29 11:48:30 volumio sudo[3542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 29 11:48:30 volumio sudo[3538]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:30 volumio sudo[3542]: pam_unix(sudo:session): session closed for user root Dec 29 11:48:30 volumio volumio[1372]: info: Upmpdcli Daemon Started Dec 29 11:49:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 29 11:49:01 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 11:49:01 volumio volumio[1372]: info: Preload queue cleared Dec 29 11:49:08 volumio volumio[1372]: Searching plugin music_service/webradio Dec 29 11:49:08 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: webradio , search Dec 29 11:49:09 volumio volumio[1372]: info: All search sources collected, pushing search results Dec 29 11:49:11 volumio volumio[1372]: info: Preload queue cleared Dec 29 11:49:11 volumio volumio[1372]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::ClearQueue Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::stop Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 11:49:11 volumio volumio[1372]: info: CorePlayQueue::clearPlayQueue Dec 29 11:49:11 volumio volumio[1372]: info: CorePlayQueue::saveQueue Dec 29 11:49:11 volumio volumio[1372]: info: CoreCommandRouter::volumioPushQueue Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::addQueueItems Dec 29 11:49:11 volumio volumio[1372]: info: CorePlayQueue::addQueueItems Dec 29 11:49:11 volumio volumio[1372]: info: Preload queue cleared Dec 29 11:49:11 volumio volumio[1372]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s303481 Dec 29 11:49:11 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 29 11:49:11 volumio volumio[1372]: info: CoreCommandRouter::volumioPushQueue Dec 29 11:49:11 volumio volumio[1372]: info: CorePlayQueue::saveQueue Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::updateTrackBlock Dec 29 11:49:11 volumio volumio[1372]: info: CorePlayQueue::getTrackBlock Dec 29 11:49:11 volumio volumio[1372]: info: CoreCommandRouter::volumioPlay Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::play index 0 Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::stop Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::play index undefined Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 11:49:11 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:11 volumio volumio[1372]: info: CoreStateMachine::startPlaybackTimer Dec 29 11:49:11 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:11 volumio volumio[1372]: info: [1767005351965] ControllerWebradio::clearAddPlayTrack Dec 29 11:49:11 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand stop Dec 29 11:49:11 volumio volumio[1372]: info: sendMpdCommand stop took 1 milliseconds Dec 29 11:49:11 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand clear Dec 29 11:49:11 volumio volumio[1372]: info: Dec 29 11:49:11 volumio volumio[1372]: ---------------------------- MPD announces system playlist update Dec 29 11:49:11 volumio volumio[1372]: info: Ignoring MPD Status Update Dec 29 11:49:11 volumio volumio[1372]: info: sendMpdCommand clear took 1 milliseconds Dec 29 11:49:11 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand load "http://usa9.fastcast4u.com/proxy/jamz?mp=/1" Dec 29 11:49:11 volumio volumio[1372]: info: Dec 29 11:49:11 volumio volumio[1372]: ---------------------------- MPD announces system playlist update Dec 29 11:49:11 volumio volumio[1372]: info: Ignoring MPD Status Update Dec 29 11:49:11 volumio volumio[1372]: error: updateQueue error: null Dec 29 11:49:11 volumio volumio[1372]: info: ------------------------------ 1ms Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand add "http://usa9.fastcast4u.com/proxy/jamz?mp=/1" Dec 29 11:49:12 volumio volumio[1372]: error: updateQueue error: null Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces system playlist update Dec 29 11:49:12 volumio volumio[1372]: info: Ignoring MPD Status Update Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 314ms Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand add "http://usa9.fastcast4u.com/proxy/jamz?mp=/1" took 0 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand play Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces system playlist update Dec 29 11:49:12 volumio volumio[1372]: info: Ignoring MPD Status Update Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 2ms Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand play took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 1ms Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces state update: player Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::getState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand status Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces state update: player Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::getState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand status Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces state update: player Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand status took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::getState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand status Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand status took 2 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand status took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseTrackInfo Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: 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":"1","artist":null,"album":null,"uri":"http://usa9.fastcast4u.com/proxy/jamz?mp=/1","trackType":""} Dec 29 11:49:12 volumio volumio[1372]: verbose: CURRENT POSITION 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState stateService play Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus stop Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 4ms Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces state update: player Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::getState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand status Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand status took 0 milliseconds Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseTrackInfo Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseTrackInfo Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: 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":"1","artist":null,"album":null,"uri":"http://usa9.fastcast4u.com/proxy/jamz?mp=/1","trackType":""} Dec 29 11:49:12 volumio volumio[1372]: verbose: CURRENT POSITION 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState stateService play Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus play Dec 29 11:49:12 volumio volumio[1372]: info: Received an update from plugin. extracting info from payload Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: 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":"1","artist":null,"album":null,"uri":"http://usa9.fastcast4u.com/proxy/jamz?mp=/1","trackType":""} Dec 29 11:49:12 volumio volumio[1372]: verbose: CURRENT POSITION 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState stateService play Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus play Dec 29 11:49:12 volumio volumio[1372]: info: Received an update from plugin. extracting info from payload Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 11ms Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 10ms Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseTrackInfo Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: 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":"1","artist":null,"album":null,"uri":"http://usa9.fastcast4u.com/proxy/jamz?mp=/1","trackType":""} Dec 29 11:49:12 volumio volumio[1372]: verbose: CURRENT POSITION 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState stateService play Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus play Dec 29 11:49:12 volumio volumio[1372]: info: Received an update from plugin. extracting info from payload Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 12ms Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces system playlist update Dec 29 11:49:12 volumio volumio[1372]: info: Ignoring MPD Status Update Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces state update: player Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::getState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand status Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces system playlist update Dec 29 11:49:12 volumio volumio[1372]: info: Ignoring MPD Status Update Dec 29 11:49:12 volumio volumio[1372]: info: Dec 29 11:49:12 volumio volumio[1372]: ---------------------------- MPD announces state update: player Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::getState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand status Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 1ms Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand status took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 1ms Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand status took 0 milliseconds Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseState Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 29 11:49:12 volumio volumio[1372]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseTrackInfo Dec 29 11:49:12 volumio volumio[1372]: verbose: ControllerMpd::parseTrackInfo Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Kudo - Can't See [2LPG]","artist":"FastCast4u.com AutoDJ","album":null,"uri":"http://usa9.fastcast4u.com/proxy/jamz?mp=/1","trackType":""} Dec 29 11:49:12 volumio volumio[1372]: verbose: CURRENT POSITION 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState stateService play Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus play Dec 29 11:49:12 volumio volumio[1372]: info: Received an update from plugin. extracting info from payload Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: ControllerMpd::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::servicePushState Dec 29 11:49:12 volumio volumio[1372]: info: CorePlayQueue::getTrack 0 Dec 29 11:49:12 volumio volumio[1372]: verbose: STATE SERVICE {"status":"play","position":0,"seek":879,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Kudo - Can't See [2LPG]","artist":"FastCast4u.com AutoDJ","album":null,"uri":"http://usa9.fastcast4u.com/proxy/jamz?mp=/1","trackType":""} Dec 29 11:49:12 volumio volumio[1372]: verbose: CURRENT POSITION 0 Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState stateService play Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::syncState currentStatus play Dec 29 11:49:12 volumio volumio[1372]: info: Received an update from plugin. extracting info from payload Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreStateMachine::pushState Dec 29 11:49:12 volumio volumio[1372]: info: CoreCommandRouter::volumioPushState Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 9ms Dec 29 11:49:12 volumio volumio[1372]: info: ------------------------------ 9ms Dec 29 11:49:28 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Dec 29 11:49:31 volumio volumio[1372]: info: CoreCommandRouter::volumioGetState Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 11:49:33 volumio volumio[1372]: info: Retrieving Cloud Streaming UI Dec 29 11:49:33 volumio volumio[1372]: info: Getting Tidal Cloud Configuration Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 11:49:33 volumio volumio[1372]: info: Getting Qobuz Cloud Configuration Dec 29 11:49:33 volumio volumio[1372]: info: Asking plugin for UI Config Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 11:49:33 volumio volumio[1372]: info: Getting Spotify Cloud Configuration Dec 29 11:49:33 volumio volumio[1372]: info: Asking plugin for UI Config Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 11:49:33 volumio volumio[1372]: info: Saving Spotify Acccount Dec 29 11:49:33 volumio volumio[1372]: info: Got Tidal Cloud Configuration Dec 29 11:49:33 volumio volumio[1372]: info: Got it Dec 29 11:49:33 volumio volumio[1372]: info: Got it Dec 29 11:49:33 volumio volumio[1372]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 11:49:33 volumio volumio[1372]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 11:49:33 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 29 11:49:35 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 29 11:49:35 volumio volumio[1372]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 29 11:49:35 volumio volumio[1372]: compat.c: read() failed: Resource temporarily unavailable Dec 29 11:49:35 volumio volumio[1372]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 11:49:35 volumio volumio[1372]: Error: dns service error: unknown Dec 29 11:49:35 volumio volumio[1372]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 29 11:49:35 volumio volumio[1372]: errorCode: -65537 Dec 29 11:49:35 volumio volumio[1372]: } Dec 29 11:49:35 volumio volumio[1372]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 11:49:35 volumio sudo[3663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 11:48' Dec 29 11:49:35 volumio sudo[3663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"