-- Logs begin at Tue 2025-12-09 21:55:40 UTC, end at Tue 2025-12-09 21:59:11 UTC. -- Dec 09 21:58:03 volumio-3b-living volumiologrotate[504]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Dec 09 21:58:03 volumio-3b-living volumiologrotate[504]: ls: cannot access '3B+': No such file or directory Dec 09 21:58:03 volumio-3b-living volumiologrotate[504]: ls: cannot access 'LIVING': No such file or directory Dec 09 21:58:18 volumio-3b-living ntpd[755]: Soliciting pool server 80.96.41.6 Dec 09 21:58:31 volumio-3b-living ntpd[755]: Soliciting pool server 2a0f:5f44:0:8210::10 Dec 09 21:58:38 volumio-3b-living volumio[968]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.205 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:38 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:38 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 09 21:58:38 volumio-3b-living sudo[2069]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:38 volumio-3b-living sudo[2069]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:38 volumio-3b-living sudo[2069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 09 21:58:38 volumio-3b-living sudo[2069]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:38 volumio-3b-living sudo[2074]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:38 volumio-3b-living sudo[2074]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:38 volumio-3b-living sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 21:58:38 volumio-3b-living sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:38 volumio-3b-living sudo[2080]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:38 volumio-3b-living sudo[2080]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:38 volumio-3b-living sudo[2069]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:38 volumio-3b-living sudo[2074]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:38 volumio-3b-living sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 21:58:38 volumio-3b-living sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:38 volumio-3b-living sudo[2086]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:38 volumio-3b-living sudo[2086]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:38 volumio-3b-living sudo[2080]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:38 volumio-3b-living sudo[2086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 09 21:58:38 volumio-3b-living sudo[2086]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:38 volumio-3b-living sudo[2086]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:38 volumio-3b-living sudo[2095]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:38 volumio-3b-living sudo[2095]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:38 volumio-3b-living sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:58:38 volumio-3b-living sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:38 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:38 volumio-3b-living sudo[2095]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:38 volumio-3b-living sudo[2097]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:38 volumio-3b-living sudo[2097]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:38 volumio-3b-living sudo[2097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:58:38 volumio-3b-living sudo[2097]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:38 volumio-3b-living sudo[2097]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:38 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:38 volumio-3b-living volumio[968]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.205 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:38 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:38 volumio-3b-living volumio[968]: verbose: New Socket.io Connection to 192.168.0.127:3000 from 192.168.0.205 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 21:58:38 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 21:58:38 volumio-3b-living ntpd[755]: Soliciting pool server 162.159.200.123 Dec 09 21:58:38 volumio-3b-living ntpd[755]: Soliciting pool server 31.25.10.207 Dec 09 21:58:39 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 21:58:39 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 21:58:41 volumio-3b-living sudo[2115]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:41 volumio-3b-living sudo[2115]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:41 volumio-3b-living sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:58:41 volumio-3b-living sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:41 volumio-3b-living sudo[2117]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:41 volumio-3b-living sudo[2117]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:41 volumio-3b-living sudo[2115]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:41 volumio-3b-living sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:58:41 volumio-3b-living sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:41 volumio-3b-living sudo[2117]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:41 volumio-3b-living volumio[968]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.205 UA: Mozilla/5.0 (Linux; Android 15; 23078PND5G Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 09 21:58:41 volumio-3b-living sudo[2121]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:41 volumio-3b-living sudo[2121]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:41 volumio-3b-living sudo[2121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:58:41 volumio-3b-living sudo[2121]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:41 volumio-3b-living sudo[2121]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:41 volumio-3b-living sudo[2123]: volumio : unable to resolve host volumio-3b-living Dec 09 21:58:41 volumio-3b-living sudo[2123]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:58:41 volumio-3b-living sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:58:41 volumio-3b-living sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:58:41 volumio-3b-living sudo[2123]: pam_unix(sudo:session): session closed for user root Dec 09 21:58:41 volumio-3b-living volumio[968]: verbose: New Socket.io Connection to 192.168.0.127 from 192.168.0.205 UA: Mozilla/5.0 (Linux; Android 15; 23078PND5G Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 09 21:58:41 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:41 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:58:41 volumio-3b-living volumio[968]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:58:41 volumio-3b-living volumio[968]: info: Listing playlists Dec 09 21:58:41 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 21:58:41 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 21:58:41 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 21:58:41 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 21:58:43 volumio-3b-living volumio[968]: info: Received Get System Info Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:43 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioPlay Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreStateMachine::play index undefined Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CorePlayQueue::getTrack 0 Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CorePlayQueue::getTrack 0 Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 09 21:58:43 volumio-3b-living volumio[968]: info: [1765317523733] ControllerWebradio::clearAddPlayTrack Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand stop Dec 09 21:58:43 volumio-3b-living volumio[968]: info: Dec 09 21:58:43 volumio-3b-living volumio[968]: ---------------------------- MPD announces state update: player Dec 09 21:58:43 volumio-3b-living volumio[968]: info: sendMpdCommand stop took 17 milliseconds Dec 09 21:58:43 volumio-3b-living volumio[968]: info: ControllerMpd::getState Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand status Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand clear Dec 09 21:58:43 volumio-3b-living volumio[968]: info: Dec 09 21:58:43 volumio-3b-living volumio[968]: ---------------------------- MPD announces system playlist update Dec 09 21:58:43 volumio-3b-living volumio[968]: info: Ignoring MPD Status Update Dec 09 21:58:43 volumio-3b-living volumio[968]: info: sendMpdCommand status took 4 milliseconds Dec 09 21:58:43 volumio-3b-living volumio[968]: info: sendMpdCommand clear took 3 milliseconds Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::parseState Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand load "https://www.magicfm.ro/listen.pls" Dec 09 21:58:43 volumio-3b-living volumio[968]: error: updateQueue error: null Dec 09 21:58:43 volumio-3b-living volumio[968]: info: ------------------------------ 7ms Dec 09 21:58:43 volumio-3b-living volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::parseTrackInfo Dec 09 21:58:43 volumio-3b-living volumio[968]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 09 21:58:43 volumio-3b-living volumio[968]: info: ------------------------------ 14ms Dec 09 21:58:43 volumio-3b-living volumio[968]: info: Dec 09 21:58:43 volumio-3b-living volumio[968]: ---------------------------- MPD announces system playlist update Dec 09 21:58:43 volumio-3b-living volumio[968]: info: Ignoring MPD Status Update Dec 09 21:58:43 volumio-3b-living volumio[968]: info: sendMpdCommand load "https://www.magicfm.ro/listen.pls" took 23 milliseconds Dec 09 21:58:43 volumio-3b-living volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 09 21:58:43 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand play Dec 09 21:58:43 volumio-3b-living volumio[968]: info: ------------------------------ 7ms Dec 09 21:58:43 volumio-3b-living volumio[968]: info: sendMpdCommand play took 5 milliseconds Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 21:58:44 volumio-3b-living volumio[968]: info: Received Get System Info Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:44 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:44 volumio-3b-living volumio[968]: info: Dec 09 21:58:44 volumio-3b-living volumio[968]: ---------------------------- MPD announces state update: player Dec 09 21:58:44 volumio-3b-living volumio[968]: info: ControllerMpd::getState Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand status Dec 09 21:58:44 volumio-3b-living volumio[968]: info: sendMpdCommand status took 31 milliseconds Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::parseState Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 21:58:44 volumio-3b-living volumio[968]: info: Dec 09 21:58:44 volumio-3b-living volumio[968]: ---------------------------- MPD announces system playlist update Dec 09 21:58:44 volumio-3b-living volumio[968]: info: Ignoring MPD Status Update Dec 09 21:58:44 volumio-3b-living volumio[968]: info: Dec 09 21:58:44 volumio-3b-living volumio[968]: ---------------------------- MPD announces state update: player Dec 09 21:58:44 volumio-3b-living volumio[968]: info: ControllerMpd::getState Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand status Dec 09 21:58:44 volumio-3b-living volumio[968]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::parseTrackInfo Dec 09 21:58:44 volumio-3b-living volumio[968]: info: ControllerMpd::pushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::servicePushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CorePlayQueue::getTrack 0 Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"75 Kbps","isStreaming":false,"title":"🎅 Magic FM 🎅🎁🎄 - Always the best music","artist":null,"album":null,"uri":"https://live.magicfm.ro/magicfm.aacp","trackType":"aacp"} Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: CURRENT POSITION 0 Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreStateMachine::syncState stateService play Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreStateMachine::syncState currentStatus stop Dec 09 21:58:44 volumio-3b-living volumio[968]: info: ------------------------------ 48ms Dec 09 21:58:44 volumio-3b-living volumio[968]: info: ------------------------------ 13ms Dec 09 21:58:44 volumio-3b-living volumio[968]: info: sendMpdCommand status took 11 milliseconds Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::parseState Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 21:58:44 volumio-3b-living volumio[968]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: ControllerMpd::parseTrackInfo Dec 09 21:58:44 volumio-3b-living volumio[968]: info: ControllerMpd::pushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::servicePushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CorePlayQueue::getTrack 0 Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1074,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"75 Kbps","isStreaming":false,"title":"🎅 Magic FM 🎅🎁🎄 - Always the best music","artist":null,"album":null,"uri":"https://live.magicfm.ro/magicfm.aacp","trackType":"aacp"} Dec 09 21:58:44 volumio-3b-living volumio[968]: verbose: CURRENT POSITION 0 Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreStateMachine::syncState stateService play Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreStateMachine::syncState currentStatus play Dec 09 21:58:44 volumio-3b-living volumio[968]: info: Received an update from plugin. extracting info from payload Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreStateMachine::pushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioPushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:58:44 volumio-3b-living volumio[968]: info: MRS: Pushing multiroomSync output Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreStateMachine::pushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioPushState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:58:44 volumio-3b-living volumio[968]: info: MRS: Pushing multiroomSync output Dec 09 21:58:44 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:44 volumio-3b-living volumio[968]: info: ------------------------------ 103ms Dec 09 21:58:44 volumio-3b-living volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Dec 09 21:58:44 volumio-3b-living volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Dec 09 21:58:44 volumio-3b-living volumio[968]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 09 21:58:47 volumio-3b-living volumio[968]: info: VolumeController::SetAlsaVolume10 Dec 09 21:58:47 volumio-3b-living volumio[968]: info: CoreStateMachine::pushState Dec 09 21:58:47 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 21:58:47 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioPushState Dec 09 21:58:47 volumio-3b-living volumio[968]: info: MRS: Pushing multiroomSync output update for this device Dec 09 21:58:47 volumio-3b-living volumio[968]: info: MRS: Pushing multiroomSync output Dec 09 21:58:47 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:47 volumio-3b-living volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Dec 09 21:58:47 volumio-3b-living volumio[968]: SPOTIFY: SPOTIFY VOLUME 15 Dec 09 21:58:47 volumio-3b-living volumio[968]: SPOTIFY: VOLUMIO VOLUME 10 Dec 09 21:58:47 volumio-3b-living volumio[968]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 09 21:58:47 volumio-3b-living volumio[968]: info: Setting Spotify Volume from Volumio: 10 Dec 09 21:58:49 volumio-3b-living volumio[968]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Dec 09 21:58:49 volumio-3b-living volumio[968]: info: Sending Spotify command with payload to local API: /player/volume Dec 09 21:58:49 volumio-3b-living go-librespot[1217]: time="2025-12-09T21:58:49Z" level=debug msg="update volume to 6553/65535" Dec 09 21:58:49 volumio-3b-living go-librespot[1217]: time="2025-12-09T21:58:49Z" level=debug msg="put connect state because VOLUME_CHANGED" Dec 09 21:58:49 volumio-3b-living go-librespot[1217]: time="2025-12-09T21:58:49Z" level=trace msg="emitting websocket event: volume" Dec 09 21:58:49 volumio-3b-living volumio[968]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} Dec 09 21:58:49 volumio-3b-living volumio[968]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 Dec 09 21:58:54 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 21:58:54 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 09 21:58:59 volumio-3b-living volumio[968]: info: Received Get System Version Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 21:58:59 volumio-3b-living volumio[968]: info: Received Get System Info Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:58:59 volumio-3b-living volumio[968]: info: Discovery: Getting this device information Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::volumioGetState Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:58:59 volumio-3b-living volumio[968]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 21:59:03 volumio-3b-living volumiologrotate[504]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Dec 09 21:59:03 volumio-3b-living volumiologrotate[504]: ls: cannot access '3B+': No such file or directory Dec 09 21:59:03 volumio-3b-living volumiologrotate[504]: ls: cannot access 'LIVING': No such file or directory Dec 09 21:59:07 volumio-3b-living volumio[968]: info: CALLMETHOD: user_interface touch_display saveVirtualKeyboardConf [object Object] Dec 09 21:59:07 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveVirtualKeyboardConf Dec 09 21:59:07 volumio-3b-living volumio[968]: info: CALLMETHOD: user_interface touch_display saveVirtualKeyboardConf [object Object] Dec 09 21:59:07 volumio-3b-living volumio[968]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveVirtualKeyboardConf Dec 09 21:59:07 volumio-3b-living sudo[2213]: volumio : unable to resolve host volumio-3b-living Dec 09 21:59:07 volumio-3b-living sudo[2213]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:59:07 volumio-3b-living sudo[2216]: volumio : unable to resolve host volumio-3b-living Dec 09 21:59:07 volumio-3b-living sudo[2216]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:59:08 volumio-3b-living sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/load-extension=.* /load-extension=\'\\/data\\/volumiokioskextensions\\/VirtualKeyboard\\/\' / /opt/volumiokiosk.sh Dec 09 21:59:08 volumio-3b-living sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:59:08 volumio-3b-living sudo[2213]: pam_unix(sudo:session): session closed for user root Dec 09 21:59:08 volumio-3b-living sudo[2216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/load-extension=.* /load-extension=\'\\/data\\/volumiokioskextensions\\/VirtualKeyboard\\/\' / /opt/volumiokiosk.sh Dec 09 21:59:08 volumio-3b-living sudo[2216]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:59:08 volumio-3b-living sudo[2216]: pam_unix(sudo:session): session closed for user root Dec 09 21:59:08 volumio-3b-living sudo[2220]: volumio : unable to resolve host volumio-3b-living Dec 09 21:59:08 volumio-3b-living sudo[2220]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:59:08 volumio-3b-living sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Dec 09 21:59:08 volumio-3b-living sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:59:08 volumio-3b-living sudo[2222]: volumio : unable to resolve host volumio-3b-living Dec 09 21:59:08 volumio-3b-living sudo[2222]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:59:08 volumio-3b-living sudo[2222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Dec 09 21:59:08 volumio-3b-living sudo[2222]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:59:08 volumio-3b-living systemd[1]: Stopping Volumio Kiosk... Dec 09 21:59:08 volumio-3b-living startx[1314]: xinit: connection to X server lost Dec 09 21:59:08 volumio-3b-living startx[1314]: [1B blob data] Dec 09 21:59:08 volumio-3b-living volumio[968]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 21:59:08 volumio-3b-living volumio[968]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 21:59:08 volumio-3b-living startx[1314]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Dec 09 21:59:09 volumio-3b-living startx[1314]: xinit: unexpected signal 15 Dec 09 21:59:09 volumio-3b-living startx[1314]: xauth: (argv):1: bad display name "volumio-3b-living:0" in "remove" command Dec 09 21:59:09 volumio-3b-living systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Dec 09 21:59:09 volumio-3b-living systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Dec 09 21:59:09 volumio-3b-living systemd[1]: Stopped Volumio Kiosk. Dec 09 21:59:09 volumio-3b-living volumio[968]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 09 21:59:09 volumio-3b-living systemd[1]: Started Volumio Kiosk. Dec 09 21:59:09 volumio-3b-living sudo[2222]: pam_unix(sudo:session): session closed for user root Dec 09 21:59:09 volumio-3b-living sudo[2220]: pam_unix(sudo:session): session closed for user root Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: Volumio Kiosk restarted. Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: Volumio Kiosk restarted. Dec 09 21:59:09 volumio-3b-living startx[2234]: hostname: System error Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: Dec 09 21:59:09 volumio-3b-living startx[2234]: xauth: (stdin):1: bad display name "volumio-3b-living:0" in "add" command Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: Dec 09 21:59:09 volumio-3b-living startx[2234]: X.Org X Server 1.20.4 Dec 09 21:59:09 volumio-3b-living startx[2234]: X Protocol Version 11, Revision 0 Dec 09 21:59:09 volumio-3b-living startx[2234]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Dec 09 21:59:09 volumio-3b-living startx[2234]: Current Operating System: Linux volumio-3b-living 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Dec 09 21:59:09 volumio-3b-living startx[2234]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=600 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=ebb277f3-4b9c-47a7-93c4-0be5bb55cc38 imgfile=/volumio_current.sqsh bootpart=UUID=EF69-E169 datapart=UUID=646e0fa6-8125-4244-820d-96807935d23e uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 09 21:59:09 volumio-3b-living startx[2234]: Build Date: 04 April 2023 07:50:56AM Dec 09 21:59:09 volumio-3b-living startx[2234]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Dec 09 21:59:09 volumio-3b-living startx[2234]: Current version of pixman: 0.36.0 Dec 09 21:59:09 volumio-3b-living startx[2234]: Before reporting problems, check http://wiki.x.org Dec 09 21:59:09 volumio-3b-living startx[2234]: to make sure that you have the latest version. Dec 09 21:59:09 volumio-3b-living startx[2234]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 09 21:59:09 volumio-3b-living startx[2234]: (++) from command line, (!!) notice, (II) informational, Dec 09 21:59:09 volumio-3b-living startx[2234]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 09 21:59:09 volumio-3b-living startx[2234]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Dec 9 21:59:09 2025 Dec 09 21:59:09 volumio-3b-living startx[2234]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 09 21:59:09 volumio-3b-living startx[2234]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:09 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 09 21:59:10 volumio-3b-living volumio[968]: info: touch_display: X display number found: 0 Dec 09 21:59:10 volumio-3b-living volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 21:59:10 volumio-3b-living volumio[968]: Error: connect EISCONN /tmp/.X11-unix/X0 Dec 09 21:59:10 volumio-3b-living volumio[968]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 09 21:59:10 volumio-3b-living volumio[968]: errno: -106, Dec 09 21:59:10 volumio-3b-living volumio[968]: code: 'EISCONN', Dec 09 21:59:10 volumio-3b-living volumio[968]: syscall: 'connect', Dec 09 21:59:10 volumio-3b-living volumio[968]: address: '/tmp/.X11-unix/X0' Dec 09 21:59:10 volumio-3b-living volumio[968]: } Dec 09 21:59:10 volumio-3b-living volumio[968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 21:59:11 volumio-3b-living sudo[2417]: volumio : unable to resolve host volumio-3b-living Dec 09 21:59:11 volumio-3b-living sudo[2417]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 09 21:59:11 volumio-3b-living sudo[2417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 21:58 Dec 09 21:59:11 volumio-3b-living sudo[2417]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"