-- Logs begin at Tue 2025-10-21 09:06:11 UTC, end at Tue 2025-11-04 05:46:34 UTC. -- Nov 04 05:45:05 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:45:05 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:45:05 volumio volumio[988]: info: Listing playlists Nov 04 05:45:05 volumio volumio[988]: info: Listing playlists Nov 04 05:45:15 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:45:15 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:45:25 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:45:25 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:45:25 volumio volumio[988]: info: Listing playlists Nov 04 05:45:25 volumio volumio[988]: info: Listing playlists Nov 04 05:45:35 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:45:35 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:45:45 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:45:45 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:45:45 volumio volumio[988]: info: Listing playlists Nov 04 05:45:45 volumio volumio[988]: info: Listing playlists Nov 04 05:45:55 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:45:55 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:00 volumio volumio[988]: info: Nov 04 05:46:00 volumio volumio[988]: ---------------------------- Client requests Volumio play Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::volumioPlay Nov 04 05:46:00 volumio volumio[988]: verbose: UNSET VOLATILE Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::play index undefined Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 04 05:46:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer Nov 04 05:46:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:00 volumio volumio[988]: info: [1762235160275] ControllerWebradio::clearAddPlayTrack Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand stop took 16 milliseconds Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand clear Nov 04 05:46:00 volumio volumio[988]: info: Nov 04 05:46:00 volumio volumio[988]: ---------------------------- MPD announces system playlist update Nov 04 05:46:00 volumio volumio[988]: info: Ignoring MPD Status Update Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand clear took 5 milliseconds Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand load "http://188.232.102.53:8004" Nov 04 05:46:00 volumio volumio[988]: error: updateQueue error: null Nov 04 05:46:00 volumio volumio[988]: info: ------------------------------ 9ms Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:46:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand add "http://188.232.102.53:8004" Nov 04 05:46:00 volumio volumio[988]: info: Nov 04 05:46:00 volumio volumio[988]: ---------------------------- MPD announces system playlist update Nov 04 05:46:00 volumio volumio[988]: info: Ignoring MPD Status Update Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand add "http://188.232.102.53:8004" took 5 milliseconds Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand play Nov 04 05:46:00 volumio volumio[988]: info: ------------------------------ 12ms Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand play took 8 milliseconds Nov 04 05:46:00 volumio volumio[988]: info: Nov 04 05:46:00 volumio volumio[988]: ---------------------------- MPD announces state update: player Nov 04 05:46:00 volumio volumio[988]: info: ControllerMpd::getState Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Nov 04 05:46:00 volumio volumio[988]: info: Nov 04 05:46:00 volumio volumio[988]: ---------------------------- MPD announces state update: player Nov 04 05:46:00 volumio volumio[988]: info: ControllerMpd::getState Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand status took 5 milliseconds Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::parseState Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand status took 10 milliseconds Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::parseState Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Nov 04 05:46:00 volumio volumio[988]: info: ControllerMpd::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::servicePushState Nov 04 05:46:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:00 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"188.232.102.53:8004","artist":null,"album":null,"uri":"http://188.232.102.53:8004","trackType":"53:8004"} Nov 04 05:46:00 volumio volumio[988]: verbose: CURRENT POSITION 0 Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 05:46:00 volumio volumio[988]: info: ------------------------------ 47ms Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand playlistinfo took 30 milliseconds Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Nov 04 05:46:00 volumio volumio[988]: info: ControllerMpd::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::servicePushState Nov 04 05:46:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:00 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"188.232.102.53:8004","artist":null,"album":null,"uri":"http://188.232.102.53:8004","trackType":"53:8004"} Nov 04 05:46:00 volumio volumio[988]: verbose: CURRENT POSITION 0 Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Nov 04 05:46:00 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 05:46:00 volumio volumio[988]: info: ------------------------------ 72ms Nov 04 05:46:00 volumio volumio[988]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! 188.232.102.53:8004 Nov 04 05:46:00 volumio volumio[988]: info: Nov 04 05:46:00 volumio volumio[988]: ---------------------------- MPD announces system playlist update Nov 04 05:46:00 volumio volumio[988]: info: Ignoring MPD Status Update Nov 04 05:46:00 volumio volumio[988]: info: Nov 04 05:46:00 volumio volumio[988]: ---------------------------- MPD announces state update: player Nov 04 05:46:00 volumio volumio[988]: info: ControllerMpd::getState Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Nov 04 05:46:00 volumio volumio[988]: info: ------------------------------ 5ms Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand status took 3 milliseconds Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::parseState Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 05:46:00 volumio volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 04 05:46:00 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Nov 04 05:46:00 volumio volumio[988]: info: ControllerMpd::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::servicePushState Nov 04 05:46:00 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:00 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"?????? - ??? ? ?????","artist":null,"album":null,"uri":"http://188.232.102.53:8004","trackType":"53:8004"} Nov 04 05:46:00 volumio volumio[988]: verbose: CURRENT POSITION 0 Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Nov 04 05:46:00 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 05:46:00 volumio volumio[988]: info: CoreStateMachine::pushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Nov 04 05:46:00 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 05:46:00 volumio volumio[988]: info: ------------------------------ 30ms Nov 04 05:46:00 volumio volumio[988]: info: [LastFM] Split composite title into artist: ?????? and title: ??? ? ????? Nov 04 05:46:01 volumio volumio[988]: error: [LastFM] track info request failed with error: Track not found Nov 04 05:46:05 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:46:05 volumio volumio[988]: info: Listing playlists Nov 04 05:46:05 volumio volumio[988]: info: Listing playlists Nov 04 05:46:15 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:46:15 volumio volumio[988]: info: Nov 04 05:46:15 volumio volumio[988]: ---------------------------- MPD announces system playlist update Nov 04 05:46:15 volumio volumio[988]: info: Ignoring MPD Status Update Nov 04 05:46:15 volumio volumio[988]: info: Nov 04 05:46:15 volumio volumio[988]: ---------------------------- MPD announces state update: player Nov 04 05:46:15 volumio volumio[988]: info: ControllerMpd::getState Nov 04 05:46:15 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Nov 04 05:46:15 volumio volumio[988]: info: ------------------------------ 4ms Nov 04 05:46:15 volumio volumio[988]: info: sendMpdCommand status took 3 milliseconds Nov 04 05:46:15 volumio volumio[988]: verbose: ControllerMpd::parseState Nov 04 05:46:15 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 04 05:46:15 volumio volumio[988]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 04 05:46:15 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Nov 04 05:46:15 volumio volumio[988]: info: ControllerMpd::pushState Nov 04 05:46:15 volumio volumio[988]: info: CoreCommandRouter::servicePushState Nov 04 05:46:15 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Nov 04 05:46:15 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15615,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"GUM & Ambrose Kenny-Smith - Minor Setback","artist":null,"album":null,"uri":"http://188.232.102.53:8004","trackType":"53:8004"} Nov 04 05:46:15 volumio volumio[988]: verbose: CURRENT POSITION 0 Nov 04 05:46:15 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Nov 04 05:46:15 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Nov 04 05:46:15 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Nov 04 05:46:15 volumio volumio[988]: info: CoreStateMachine::pushState Nov 04 05:46:15 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Nov 04 05:46:15 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 05:46:15 volumio volumio[988]: info: CoreStateMachine::pushState Nov 04 05:46:15 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Nov 04 05:46:15 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 05:46:15 volumio volumio[988]: info: ------------------------------ 27ms Nov 04 05:46:15 volumio volumio[988]: info: [LastFM] Split composite title into artist: GUM & Ambrose Kenny-Smith and title: Minor Setback Nov 04 05:46:16 volumio volumio[988]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Minor Setback","url":"https://www.last.fm/music/GUM+&+Ambrose+Kenny-Smith/_/Minor+Setback","duration":"209000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"989","playcount":"5640","artist":{"name":"GUM & Ambrose Kenny-Smith","url":"https://www.last.fm/music/GUM+&+Ambrose+Kenny-Smith"},"album":{"artist":"Ambrose Kenny-Smith & GUM","title":"Minor Setback - Single","url":"https://www.last.fm/music/Ambrose+Kenny-Smith+&+GUM/Minor+Setback+-+Single","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/78379f8cdcc7a43fcfcd6be4d037f192.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/78379f8cdcc7a43fcfcd6be4d037f192.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/78379f8cdcc7a43fcfcd6be4d037f192.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/78379f8cdcc7a43fcfcd6be4d037f192.png","@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":""}} Nov 04 05:46:16 volumio volumio[988]: info: [LastFM] Updated missing track duration: 209000 Nov 04 05:46:16 volumio volumio[988]: info: [LastFM] Updated missing track album: Minor Setback - Single Nov 04 05:46:21 volumio sudo[14691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 04 05:46:21 volumio sudo[14691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 05:46:21 volumio sudo[14691]: pam_unix(sudo:session): session closed for user root Nov 04 05:46:21 volumio sudo[14694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 04 05:46:21 volumio sudo[14694]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 05:46:21 volumio sudo[14694]: pam_unix(sudo:session): session closed for user root Nov 04 05:46:22 volumio sudo[14712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 04 05:46:22 volumio sudo[14712]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 05:46:22 volumio sudo[14714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 04 05:46:22 volumio sudo[14712]: pam_unix(sudo:session): session closed for user root Nov 04 05:46:22 volumio sudo[14714]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 05:46:22 volumio sudo[14714]: pam_unix(sudo:session): session closed for user root Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 05:46:22 volumio volumio[988]: info: Listing playlists Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 04 05:46:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 04 05:46:24 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 04 05:46:25 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Nov 04 05:46:25 volumio volumio[988]: info: Listing playlists Nov 04 05:46:25 volumio volumio[988]: info: Listing playlists Nov 04 05:46:33 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 05:46:33 volumio volumio[988]: { Error: connect ETIMEDOUT 130.211.19.189:80 Nov 04 05:46:33 volumio volumio[988]: at Object._errnoException (util.js:1022:11) Nov 04 05:46:33 volumio volumio[988]: at _exceptionWithHostPort (util.js:1044:20) Nov 04 05:46:33 volumio volumio[988]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Nov 04 05:46:33 volumio volumio[988]: code: 'ETIMEDOUT', Nov 04 05:46:33 volumio volumio[988]: errno: 'ETIMEDOUT', Nov 04 05:46:33 volumio volumio[988]: syscall: 'connect', Nov 04 05:46:33 volumio volumio[988]: address: '130.211.19.189', Nov 04 05:46:33 volumio volumio[988]: port: 80 } Nov 04 05:46:33 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 05:46:34 volumio sudo[14765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 05:45 Nov 04 05:46:34 volumio sudo[14765]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"