Dec 30 16:00:02 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:03 matrix nmbd[2626]: [2025/12/30 16:00:03.123675, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 30 16:00:03 matrix nmbd[2626]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.127 for name WORKGROUP<1d>. Dec 30 16:00:03 matrix nmbd[2626]: This response was from IP 192.168.1.124, reporting an IP address of 192.168.1.124. Dec 30 16:00:03 matrix nmbd[2626]: [2025/12/30 16:00:03.124106, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 30 16:00:03 matrix nmbd[2626]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.127 for name WORKGROUP<1d>. Dec 30 16:00:03 matrix nmbd[2626]: This response was from IP 192.168.1.124, reporting an IP address of 192.168.1.124. Dec 30 16:00:03 matrix nmbd[2626]: [2025/12/30 16:00:03.124148, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 30 16:00:03 matrix nmbd[2626]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.1.127 for name WORKGROUP<1d>. Dec 30 16:00:03 matrix nmbd[2626]: This response was from IP 192.168.1.124, reporting an IP address of 192.168.1.124. Dec 30 16:00:06 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:10 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:14 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:18 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:22 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:26 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:30 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:33 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Dec 30 16:00:33 matrix volumio[1378]: info: Share Roobuntu successfully unmounted Dec 30 16:00:33 matrix sudo[5784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.70.145/Data/Music/MacMini/ /mnt/NAS/Roobuntu Dec 30 16:00:33 matrix sudo[5784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:00:33 matrix kernel: CIFS: Attempting to mount //192.168.70.145/Data/Music/MacMini Dec 30 16:00:33 matrix kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.70.145\Data Dec 30 16:00:33 matrix sudo[5784]: pam_unix(sudo:session): session closed for user root Dec 30 16:00:33 matrix volumio[1378]: info: An error occurred mounting the new share. Rolling back configuration Dec 30 16:00:33 matrix kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Dec 30 16:00:34 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 16:00:34 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:38 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:42 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:46 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:47 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Dec 30 16:00:47 matrix volumio[1378]: info: Share Roobuntu successfully unmounted Dec 30 16:00:47 matrix sudo[5823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 182.168.70.145:Data/Music/MacMini/ /mnt/NAS/Roobuntu Dec 30 16:00:47 matrix sudo[5823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:00:50 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:54 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:56 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Dec 30 16:00:56 matrix volumio[1378]: info: Share Roobuntu successfully unmounted Dec 30 16:00:56 matrix sudo[5849]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.70.145:Data/Music/MacMini/ /mnt/NAS/Roobuntu Dec 30 16:00:56 matrix sudo[5849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 30 16:00:56 matrix systemd[1]: Starting rpc-statd.service - NFS status monitor for NFSv2/3 locking.... Dec 30 16:00:56 matrix rpc.statd[5859]: Version 2.6.2 starting Dec 30 16:00:56 matrix rpc.statd[5859]: Flags: TI-RPC Dec 30 16:00:56 matrix rpc.statd[5859]: Failed to read /var/lib/nfs/state: Success Dec 30 16:00:56 matrix rpc.statd[5859]: Initializing NSM state Dec 30 16:00:56 matrix systemd[1]: Started rpc-statd.service - NFS status monitor for NFSv2/3 locking.. Dec 30 16:00:56 matrix systemd[1]: Reloading. Dec 30 16:00:56 matrix systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 30 16:00:56 matrix systemd[1]: Starting fstrim.service - Discard unused blocks on filesystems from /etc/fstab... Dec 30 16:00:58 matrix fstrim[5879]: /boot: 272.4 MiB (285679616 bytes) trimmed on /dev/mmcblk0p1 Dec 30 16:00:58 matrix systemd[1]: fstrim.service: Deactivated successfully. Dec 30 16:00:58 matrix systemd[1]: Finished fstrim.service - Discard unused blocks on filesystems from /etc/fstab. Dec 30 16:00:58 matrix volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 16:00:59 matrix systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 30 16:00:59 matrix systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. 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) 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"