Dec 30 16:01:02 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:01:06 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:01:10 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:01:14 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:01:17 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Dec 30 16:01:17 matrix volumio[1378]: info: Share Roobuntu successfully unmounted Dec 30 16:01:17 matrix sudo[5979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.70.145:Data /mnt/NAS/Roobuntu Dec 30 16:01:17 matrix sudo[5979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:18 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:01:23 matrix volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:01:26 matrix volumio[1378]: info: Retrieving Cloud Streaming UI Dec 30 16:01:26 matrix volumio[1378]: info: Getting Tidal Cloud Configuration Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:01:26 matrix volumio[1378]: info: Getting Qobuz Cloud Configuration Dec 30 16:01:26 matrix volumio[1378]: info: Asking plugin for UI Config Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:01:26 matrix volumio[1378]: info: Getting Spotify Cloud Configuration Dec 30 16:01:26 matrix volumio[1378]: info: Asking plugin for UI Config Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:01:26 matrix volumio[1378]: info: Saving Spotify Acccount Dec 30 16:01:26 matrix volumio[1378]: info: Got Tidal Cloud Configuration Dec 30 16:01:26 matrix volumio[1378]: info: Got it Dec 30 16:01:26 matrix volumio[1378]: info: Got it Dec 30 16:01:26 matrix volumio[1378]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:01:26 matrix volumio[1378]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:01:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 16:01:28 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 30 16:01:28 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand rescan Dec 30 16:01:28 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:28 matrix volumio[1378]: info: Dec 30 16:01:28 matrix volumio[1378]: ---------------------------- MPD announces state update: update Dec 30 16:01:28 matrix volumio[1378]: info: sendMpdCommand rescan took 1 milliseconds Dec 30 16:01:28 matrix volumio[1378]: info: ControllerMpd::getState Dec 30 16:01:28 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:28 matrix volumio[1378]: info: sendMpdCommand status took 0 milliseconds Dec 30 16:01:28 matrix volumio[1378]: info: sendMpdCommand status took 0 milliseconds Dec 30 16:01:28 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: info: Command Router : Notfying DB Updatetrue Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:30 matrix volumio[1378]: info: Dec 30 16:01:30 matrix volumio[1378]: ---------------------------- MPD announces state update: update Dec 30 16:01:30 matrix volumio[1378]: info: ControllerMpd::getState Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:30 matrix volumio[1378]: info: Dec 30 16:01:30 matrix volumio[1378]: ---------------------------- MPD announces state update: update Dec 30 16:01:30 matrix volumio[1378]: info: ControllerMpd::getState Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand status took 6 milliseconds Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand status took 6 milliseconds Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand status took 5 milliseconds Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand status took 5 milliseconds Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: info: Command Router : Notfying DB Updatefalse Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::Close All Modals sent Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: info: Command Router : Notfying DB Updatefalse Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::Close All Modals sent Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 16:01:30 matrix volumio[1378]: info: ControllerMpd::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::servicePushState Dec 30 16:01:30 matrix volumio[1378]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:30 matrix volumio[1378]: verbose: STATE SERVICE {"status":"play","position":1,"seek":929193,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Pizza Hotline - DREAM SELECT","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Dec 30 16:01:30 matrix volumio[1378]: verbose: CURRENT POSITION 0 Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 30 16:01:30 matrix volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: ------------------------------ 1287ms Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 30 16:01:30 matrix volumio[1378]: info: ControllerMpd::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::servicePushState Dec 30 16:01:30 matrix volumio[1378]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:30 matrix volumio[1378]: verbose: STATE SERVICE {"status":"play","position":1,"seek":930315,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Pizza Hotline - DREAM SELECT","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Dec 30 16:01:30 matrix volumio[1378]: verbose: CURRENT POSITION 0 Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 30 16:01:30 matrix volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: ControllerMpd::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::servicePushState Dec 30 16:01:30 matrix volumio[1378]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:30 matrix volumio[1378]: verbose: STATE SERVICE {"status":"play","position":1,"seek":930315,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Pizza Hotline - DREAM SELECT","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Dec 30 16:01:30 matrix volumio[1378]: verbose: CURRENT POSITION 0 Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 30 16:01:30 matrix volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: ------------------------------ 23ms Dec 30 16:01:30 matrix volumio[1378]: info: ------------------------------ 22ms Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:30 matrix volumio[1378]: info: Dec 30 16:01:30 matrix volumio[1378]: ---------------------------- MPD announces state update: update Dec 30 16:01:30 matrix volumio[1378]: info: ControllerMpd::getState Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand status took 4 milliseconds Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: info: Command Router : Notfying DB Updatefalse Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::Close All Modals sent Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand status took 8 milliseconds Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 16:01:30 matrix volumio[1378]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 16:01:30 matrix volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 30 16:01:30 matrix volumio[1378]: info: ControllerMpd::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::servicePushState Dec 30 16:01:30 matrix volumio[1378]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:30 matrix volumio[1378]: verbose: STATE SERVICE {"status":"play","position":1,"seek":930315,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Pizza Hotline - DREAM SELECT","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Dec 30 16:01:30 matrix volumio[1378]: verbose: CURRENT POSITION 0 Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 30 16:01:30 matrix volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:30 matrix volumio[1378]: info: ------------------------------ 14ms Dec 30 16:01:30 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:01:33 matrix volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 30 16:01:36 matrix volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:01:36 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 16:01:36 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 16:01:36 matrix sudo[6053]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 16:01:36 matrix sudo[6053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:36 matrix sudo[6053]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:36 matrix sudo[6058]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 16:01:36 matrix sudo[6058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:36 matrix sudo[6065]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 16:01:36 matrix sudo[6058]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:36 matrix sudo[6065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:36 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 16:01:36 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 16:01:36 matrix sudo[6076]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 16:01:36 matrix sudo[6071]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 16:01:36 matrix sudo[6076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:36 matrix sudo[6071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:36 matrix sudo[6065]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:36 matrix sudo[6071]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:36 matrix sudo[6076]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:36 matrix sudo[6078]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 16:01:36 matrix sudo[6078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:36 matrix sudo[6084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 16:01:36 matrix sudo[6078]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:36 matrix sudo[6084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:38 matrix sudo[6084]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:46 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 16:01:46 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 16:01:46 matrix sudo[6110]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 16:01:46 matrix sudo[6110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:48 matrix volumio[1378]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Dec 30 16:01:48 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Dec 30 16:01:49 matrix sudo[6110]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:49 matrix sudo[6127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Dec 30 16:01:49 matrix sudo[6127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:49 matrix sudo[6127]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:49 matrix volumio[1378]: info: Permissions for /etc/hostapd/hostapd.conf Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 30 16:01:49 matrix volumio[1378]: info: Discovery: Restarting Advertising due to device name change Dec 30 16:01:49 matrix sudo[6130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 30 16:01:49 matrix sudo[6130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:49 matrix systemd[1]: Stopping wireless.service - Wireless Services... Dec 30 16:01:49 matrix systemd[1]: wireless.service: Deactivated successfully. Dec 30 16:01:49 matrix systemd[1]: Stopped wireless.service - Wireless Services. Dec 30 16:01:49 matrix systemd[1]: Starting wireless.service - Wireless Services... Dec 30 16:01:49 matrix wireless.js[6136]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 30 16:01:49 matrix wireless.js[6136]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 30 16:01:49 matrix wireless.js[6136]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 30 16:01:49 matrix wireless.js[6136]: WIRELESS.JS: Cleaning previous... Dec 30 16:01:49 matrix sudo[6154]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 30 16:01:49 matrix sudo[6154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 30 16:01:49 matrix sudo[6154]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:49 matrix sudo[6156]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 30 16:01:49 matrix sudo[6156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 30 16:01:49 matrix volumio[1378]: info: Dec 30 16:01:49 matrix volumio[1378]: ---------------------------- MPD announces system playlist update Dec 30 16:01:49 matrix volumio[1378]: info: Ignoring MPD Status Update Dec 30 16:01:49 matrix volumio[1378]: info: Dec 30 16:01:49 matrix volumio[1378]: ---------------------------- MPD announces state update: player Dec 30 16:01:49 matrix volumio[1378]: info: ControllerMpd::getState Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:49 matrix volumio[1378]: info: Dec 30 16:01:49 matrix volumio[1378]: ---------------------------- MPD announces system playlist update Dec 30 16:01:49 matrix volumio[1378]: info: Ignoring MPD Status Update Dec 30 16:01:49 matrix volumio[1378]: info: Dec 30 16:01:49 matrix volumio[1378]: ---------------------------- MPD announces state update: player Dec 30 16:01:49 matrix volumio[1378]: info: ControllerMpd::getState Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand status Dec 30 16:01:49 matrix volumio[1378]: info: ------------------------------ 1ms Dec 30 16:01:49 matrix volumio[1378]: info: sendMpdCommand status took 1 milliseconds Dec 30 16:01:49 matrix volumio[1378]: info: ------------------------------ 1ms Dec 30 16:01:49 matrix volumio[1378]: info: sendMpdCommand status took 1 milliseconds Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::parseState Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 16:01:49 matrix volumio[1378]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 30 16:01:49 matrix volumio[1378]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 30 16:01:49 matrix volumio[1378]: verbose: ControllerMpd::parseTrackInfo Dec 30 16:01:49 matrix volumio[1378]: info: ControllerMpd::pushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::servicePushState Dec 30 16:01:49 matrix volumio[1378]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:49 matrix volumio[1378]: verbose: STATE SERVICE {"status":"play","position":1,"seek":950069,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Jessy Lanza - Slapped By My Life","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Dec 30 16:01:49 matrix volumio[1378]: verbose: CURRENT POSITION 0 Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 30 16:01:49 matrix volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:49 matrix volumio[1378]: info: ControllerMpd::pushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::servicePushState Dec 30 16:01:49 matrix volumio[1378]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:49 matrix volumio[1378]: verbose: STATE SERVICE {"status":"play","position":1,"seek":950069,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Jessy Lanza - Slapped By My Life","artist":"KEXP 90.3 FM","album":null,"uri":"http://live-aacplus-64.kexp.org/kexp64.aac","trackType":"aac"} Dec 30 16:01:49 matrix volumio[1378]: verbose: CURRENT POSITION 0 Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::syncState stateService play Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::syncState currentStatus play Dec 30 16:01:49 matrix volumio[1378]: info: Received an update from plugin. extracting info from payload Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreStateMachine::pushState Dec 30 16:01:49 matrix volumio[1378]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:49 matrix volumio[1378]: info: ------------------------------ 4ms Dec 30 16:01:49 matrix volumio[1378]: info: ------------------------------ 4ms Dec 30 16:01:50 matrix sudo[6156]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:50 matrix sudo[5849]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:50 matrix sudo[5979]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:50 matrix sudo[5823]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:50 matrix wireless.js[6136]: WIRELESS.JS: Stopped aP Dec 30 16:01:50 matrix sudo[6164]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 30 16:01:50 matrix sudo[6164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:50 matrix sudo[6164]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:50 matrix kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 30 16:01:50 matrix sudo[6166]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 30 16:01:50 matrix sudo[6166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:50 matrix sudo[6166]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:50 matrix sudo[6174]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 30 16:01:50 matrix sudo[6174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:50 matrix sudo[6174]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:50 matrix sudo[6176]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 30 16:01:50 matrix sudo[6176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:50 matrix volumio[1378]: info: Discovery: A device disappeared from network Dec 30 16:01:50 matrix volumio[1378]: info: Discovery: A device disappeared from network Dec 30 16:01:52 matrix sudo[6176]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:52 matrix wireless.js[6136]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: DE Dec 30 16:01:52 matrix sudo[6185]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 30 16:01:52 matrix sudo[6185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:52 matrix sudo[6185]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:52 matrix sudo[6187]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Dec 30 16:01:52 matrix sudo[6187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:52 matrix sudo[6187]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:52 matrix wireless.js[6136]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: DE Dec 30 16:01:52 matrix wireless.js[6136]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 30 16:01:52 matrix wireless.js[6136]: WIRELESS.JS: Notified systemd about wireless ready Dec 30 16:01:52 matrix kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 30 16:01:52 matrix systemd[1]: Started wireless.service - Wireless Services. Dec 30 16:01:52 matrix sudo[6130]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:54 matrix volumio[1378]: info: Discovery: Started advertising with name: Matrix Dec 30 16:01:54 matrix volumio[1378]: info: CoreCommandRouter::volumioGetState Dec 30 16:01:55 matrix volumio[1378]: compat.c: read() failed: Resource temporarily unavailable Dec 30 16:01:55 matrix volumio[1378]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 16:01:55 matrix volumio[1378]: Error: dns service error: unknown Dec 30 16:01:55 matrix volumio[1378]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 30 16:01:55 matrix volumio[1378]: errorCode: -65537 Dec 30 16:01:55 matrix volumio[1378]: } Dec 30 16:01:55 matrix volumio[1378]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 16:01:55 matrix sudo[6217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-30 16:00' Dec 30 16:01:55 matrix sudo[6217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:55 matrix sudo[6217]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:56 matrix volumio-remote-updater[997]: [2025-12-30 12:01:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 30 16:01:56 matrix volumio-remote-updater[997]: [2025-12-30 12:01:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 30 16:01:56 matrix systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 30 16:01:56 matrix systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 30 16:01:56 matrix systemd[1]: volumio.service: Consumed 28min 44.871s CPU time. Dec 30 16:01:56 matrix systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 30 16:01:56 matrix systemd[1]: dynamicswap.service: Deactivated successfully. Dec 30 16:01:56 matrix systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 30 16:01:56 matrix systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 30 16:01:56 matrix systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 30 16:01:56 matrix systemd[1]: volumio.service: Consumed 28min 44.871s CPU time. Dec 30 16:01:56 matrix systemd[1]: Started volumio.service - Volumio Backend Module. Dec 30 16:01:56 matrix systemd[1]: dynamicswap.service: Deactivated successfully. Dec 30 16:01:56 matrix volumio[6256]: info: ------------------------------------------- Dec 30 16:01:56 matrix volumio[6256]: info: ----- Volumio3 ---- Dec 30 16:01:56 matrix volumio[6256]: info: ------------------------------------------- Dec 30 16:01:56 matrix volumio[6256]: info: ----- System startup ---- Dec 30 16:01:56 matrix volumio[6256]: info: ------------------------------------------- Dec 30 16:01:56 matrix volumio[6256]: info: MYVOLUMIO Environment detected Dec 30 16:01:57 matrix volumio[6256]: info: Plugin folders cleanup Dec 30 16:01:57 matrix volumio[6256]: info: Scanning into folder /volumio/app/plugins/ Dec 30 16:01:57 matrix volumio[6256]: info: Scanning category audio_interface Dec 30 16:01:57 matrix volumio[6256]: info: Scanning category miscellanea Dec 30 16:01:57 matrix volumio[6256]: info: Scanning category music_service Dec 30 16:01:57 matrix volumio[6256]: info: Scanning category plugins.json Dec 30 16:01:57 matrix volumio[6256]: info: Scanning category system_controller Dec 30 16:01:57 matrix volumio[6256]: info: Scanning category user_interface Dec 30 16:01:57 matrix volumio[6256]: info: Scanning into folder /data/plugins/ Dec 30 16:01:57 matrix volumio[6256]: info: Plugin folders cleanup completed Dec 30 16:01:57 matrix volumio[6256]: info: ------------------------------------------- Dec 30 16:01:57 matrix volumio[6256]: info: ----- Core plugins startup ---- Dec 30 16:01:57 matrix volumio[6256]: info: ------------------------------------------- Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugins from folder /volumio/app/plugins/ Dec 30 16:01:57 matrix volumio[6256]: info: Adding plugin upnp to MyMusic Plugins Dec 30 16:01:57 matrix volumio[6256]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 30 16:01:57 matrix volumio[6256]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugins from folder /data/plugins/ Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "system"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "appearance"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "network"... Dec 30 16:01:57 matrix volumio[6256]: info: Refreshing Cached IP Addresses Dec 30 16:01:57 matrix sudo[6284]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 16:01:57 matrix sudo[6284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:57 matrix sudo[6284]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:57 matrix sudo[6286]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 16:01:57 matrix sudo[6286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:57 matrix sudo[6286]: pam_unix(sudo:session): session closed for user root Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "services"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "alsa_controller"... Dec 30 16:01:57 matrix sudo[6295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 16:01:57 matrix sudo[6295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "wizard"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "networkfs"... Dec 30 16:01:57 matrix volumio[6256]: info: Starting Udev Watcher for removable devices Dec 30 16:01:57 matrix sudo[6315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.70.145:Data /mnt/NAS/Roobuntu Dec 30 16:01:57 matrix sudo[6315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:01:57 matrix volumio[6256]: info: Ignoring mount for partition: boot Dec 30 16:01:57 matrix volumio[6256]: info: Ignoring mount for partition: volumio Dec 30 16:01:57 matrix volumio[6256]: info: Ignoring mount for partition: volumio_data Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "volumio_command_line_client"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "upnp"... Dec 30 16:01:57 matrix volumio[6256]: info: [1767096117262] Starting Upmpd Daemon Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "my_music"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "mpd"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "upnp_browser"... Dec 30 16:01:57 matrix volumio[6256]: info: Starting UPNP Browser Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "alarm-clock"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "airplay_emulation"... Dec 30 16:01:57 matrix volumio[6256]: info: Starting Shairport Sync Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "last_100"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "webradio"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "i2s_dacs"... Dec 30 16:01:57 matrix volumio[6256]: info: I2S DAC not set, start Auto-detection Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "volumiodiscovery"... Dec 30 16:01:57 matrix volumio[6256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 16:01:57 matrix node[6256]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 30 16:01:57 matrix volumio[6256]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 16:01:57 matrix node[6256]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 16:01:57 matrix volumio[6256]: *** WARNING *** For more information see Dec 30 16:01:57 matrix node[6256]: *** WARNING *** For more information see Dec 30 16:01:57 matrix volumio[6256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 16:01:57 matrix node[6256]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 30 16:01:57 matrix volumio[6256]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 16:01:57 matrix node[6256]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 30 16:01:57 matrix volumio[6256]: *** WARNING *** For more information see Dec 30 16:01:57 matrix node[6256]: *** WARNING *** For more information see Dec 30 16:01:57 matrix volumio[6256]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 30 16:01:57 matrix volumio[6256]: info: Discovery: Started advertising with name: Matrix Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "outputs"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "albumart"... Dec 30 16:01:57 matrix volumio[6256]: info: Plugin example_plugin is not enabled Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "inputs"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "updater_comm"... Dec 30 16:01:57 matrix volumio[6256]: info: Plugin mpdemulation is not enabled Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "rest_api"... Dec 30 16:01:57 matrix volumio[6256]: info: Loading plugin "websocket"... Dec 30 16:01:57 matrix volumio[6256]: info: Starting Socket.io Server version 1.7.4 Dec 30 16:01:57 matrix volumio[6256]: info: Loading i18n strings for locale en Dec 30 16:01:57 matrix volumio[6256]: Updating browse sources language Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::initPlayerControls Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: Express server listening on port 3000 Dec 30 16:01:57 matrix volumio[6256]: [Metrics] WebUI: 1s 1.39ms Dec 30 16:01:57 matrix volumio[6256]: info: CoreStateMachine::resetVolumioState Dec 30 16:01:57 matrix volumio[6256]: info: CoreStateMachine::getcurrentVolume Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioRetrievevolume Dec 30 16:01:57 matrix volumio[6256]: info: CoreStateMachine::pushState Dec 30 16:01:57 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 16:01:57 matrix volumio[6256]: info: Volumio Network Manager: Network status updated: 1 Dec 30 16:01:57 matrix volumio[6320]: Forking 3 albumart workers Dec 30 16:01:57 matrix volumio[6256]: info: Reloading queue from file Dec 30 16:01:57 matrix volumio[6256]: verbose: New Socket.io Connection to 192.168.1.127 from 192.168.1.13 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 30 16:01:57 matrix volumio[6256]: info: CoreStateMachine::setRepeat null single undefined Dec 30 16:01:57 matrix volumio[6256]: info: CoreStateMachine::pushState Dec 30 16:01:57 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:57 matrix volumio[6256]: info: CoreStateMachine::setRandom null Dec 30 16:01:57 matrix volumio[6256]: info: CoreStateMachine::pushState Dec 30 16:01:57 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioPushState Dec 30 16:01:57 matrix volumio[6256]: info: Setting Device type: Raspberry PI Dec 30 16:01:57 matrix volumio[6256]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 30 16:01:57 matrix volumio[6256]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Dec 30 16:01:57 matrix volumio[6256]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioGetVisibleSources Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:01:57 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:01:57 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 30 16:01:57 matrix volumio[6256]: info: Received Get System Info Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 30 16:01:57 matrix volumio[6256]: info: Discovery: Getting this device information Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:01:57 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 30 16:01:57 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:01:57 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:01:57 matrix volumio[6256]: info: Listing playlists Dec 30 16:01:58 matrix volumio[6335]: Starting albumart workers Dec 30 16:01:58 matrix volumio[6336]: Starting albumart workers Dec 30 16:01:58 matrix volumio[6337]: Starting albumart workers Dec 30 16:01:59 matrix sudo[6295]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:00 matrix volumio[6256]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:02:00 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 16:02:00 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 16:02:00 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 30 16:02:00 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 30 16:02:00 matrix volumio[6256]: info: Received Get System Version Dec 30 16:02:00 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 30 16:02:01 matrix volumio-remote-updater[997]: [2025-12-30 12:02:01] [connect] Successful connection Dec 30 16:02:01 matrix volumio-remote-updater[997]: [2025-12-30 12:02:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1767096121 101 Dec 30 16:02:01 matrix volumio[6256]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Dec 30 16:02:03 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:02:03 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:02:05 matrix volumio[6256]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:02:05 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 16:02:05 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 30 16:02:05 matrix sudo[6431]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 30 16:02:05 matrix sudo[6436]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 16:02:05 matrix sudo[6431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:05 matrix sudo[6436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:05 matrix sudo[6431]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:05 matrix sudo[6436]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:05 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 30 16:02:05 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 30 16:02:05 matrix sudo[6453]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 16:02:05 matrix sudo[6453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:05 matrix sudo[6455]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 16:02:05 matrix sudo[6455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:05 matrix sudo[6448]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 16:02:05 matrix sudo[6448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:05 matrix sudo[6455]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:05 matrix sudo[6448]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:05 matrix sudo[6442]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 30 16:02:05 matrix sudo[6442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:05 matrix sudo[6453]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:05 matrix sudo[6442]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:05 matrix sudo[6462]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 30 16:02:05 matrix sudo[6462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:07 matrix sudo[6489]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 30 16:02:07 matrix sudo[6489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:07 matrix sudo[6491]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 30 16:02:07 matrix sudo[6491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:07 matrix sudo[6489]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:07 matrix sudo[6491]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:07 matrix sudo[6495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 30 16:02:07 matrix sudo[6495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:02:07 matrix sudo[6495]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:07 matrix volumio[6256]: info: Upmpdcli Daemon Started Dec 30 16:02:07 matrix sudo[6462]: pam_unix(sudo:session): session closed for user root Dec 30 16:02:08 matrix volumio[6256]: info: Discovery: adding cf006d19-3bca-40bd-a512-b24302a17a01 Dec 30 16:02:08 matrix volumio[6256]: info: Discovery: Found device Matrix Dec 30 16:02:08 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:02:08 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:02:08 matrix volumio[6256]: info: Discovery: this is already registered, cf006d19-3bca-40bd-a512-b24302a17a01 Dec 30 16:02:08 matrix volumio[6256]: info: Discovery: Found device Matrix Dec 30 16:02:08 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:02:08 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:02:10 matrix volumio[6256]: info: CoreCommandRouter::volumioGetState Dec 30 16:02:10 matrix volumio[6256]: info: CorePlayQueue::getTrack 0 Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:02:13 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 16:02:17 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:02:21 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:02:25 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:02:26 matrix volumio[6256]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 30 16:02:26 matrix volumio[6256]: verbose: ControllerMpd::sendMpdCommand rescan Dec 30 16:02:26 matrix volumio[6256]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 16:02:27 matrix volumio[6256]: TypeError: Cannot read properties of undefined (reading 'then') Dec 30 16:02:27 matrix volumio[6256]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Dec 30 16:02:27 matrix volumio[6256]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1904:15) Dec 30 16:02:27 matrix volumio[6256]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 30 16:02:27 matrix volumio[6256]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1098:26) Dec 30 16:02:27 matrix volumio[6256]: at Socket.emit (node:events:514:28) Dec 30 16:02:27 matrix volumio[6256]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 30 16:02:27 matrix volumio[6256]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 30 16:02:27 matrix volumio[6256]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 16:02:27 matrix sudo[6580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-30 16:01' Dec 30 16:02:27 matrix sudo[6580]: 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"