-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2025-05-30 09:16:33 UTC. -- May 30 09:15:04 volumio volumio[31765]: info: CorePlayQueue::getTrack 4 May 30 09:15:04 volumio volumio[31765]: info: CorePlayQueue::getTrack 5 May 30 09:15:04 volumio volumio[31765]: info: Prefetching next song May 30 09:15:04 volumio volumio[31765]: info: [1748596504039] ControllerQobuz::prefetch May 30 09:15:06 volumio volumio[31765]: info: getStreamUrl took 2867 milliseconds May 30 09:15:06 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=1906090&eid=34912269&fmt=6&profile=raw&app_id=539451548&cid=1343785&etsp=1748600106&hmac=-Ls616qXKB73Xl3O_7B6MK5TTpg" May 30 09:15:08 volumio volumio[31765]: info: CoreStateMachine::startPlaybackTimer May 30 09:15:08 volumio volumio[31765]: info: CorePlayQueue::getTrack 5 May 30 09:15:08 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1906090&eid=34912269&fmt=6&profile=raw&app_id=539451548&cid=1343785&etsp=1748600106&hmac=-Ls616qXKB73Xl3O_7B6MK5TTpg" May 30 09:15:08 volumio volumio[31765]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=1906090&eid=34912269&fmt=6&profile=raw&app_id=539451548&cid=1343785&etsp=1748600106&hmac=-Ls616qXKB73Xl3O_7B6MK5TTpg" took 1 milliseconds May 30 09:15:08 volumio volumio[31765]: info: CoreStateMachine::setConsumeUpdateService mpd May 30 09:15:08 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand consume 1 May 30 09:15:08 volumio volumio[31765]: info: May 30 09:15:08 volumio volumio[31765]: ---------------------------- MPD announces system playlist update May 30 09:15:08 volumio volumio[31765]: info: Ignoring MPD Status Update May 30 09:15:08 volumio volumio[31765]: info: sendMpdCommand consume 1 took 2 milliseconds May 30 09:15:08 volumio volumio[31765]: info: ------------------------------ 2ms May 30 09:15:27 volumio volumio[31765]: info: Tunnel connection is inactive, restarting it May 30 09:15:27 volumio volumio[31765]: info: Starting Tunnel 1 May 30 09:15:27 volumio volumio[31765]: info: Starting Tunnel Connection Checker May 30 09:15:27 volumio sudo[32463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 30 09:15:27 volumio sudo[32463]: pam_unix(sudo:session): session opened for user root by (uid=0) May 30 09:15:27 volumio autossh[32442]: received signal to exit (15) May 30 09:15:27 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... May 30 09:15:27 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM May 30 09:15:27 volumio systemd[1]: sshtunnel.service: Succeeded. May 30 09:15:27 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. May 30 09:15:27 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 30 09:15:27 volumio sudo[32463]: pam_unix(sudo:session): session closed for user root May 30 09:15:27 volumio volumio[31765]: info: Remote SSH Started May 30 09:15:27 volumio autossh[32466]: port set to 0, monitoring disabled May 30 09:15:27 volumio autossh[32466]: starting ssh (count 1) May 30 09:15:27 volumio autossh[32466]: ssh child pid is 32469 May 30 09:15:27 volumio volumio[31765]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 30 09:15:33 volumio volumio[31765]: info: CoreCommandRouter::volumioGetState May 30 09:15:35 volumio volumiossh-tunnel[32465]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. May 30 09:15:45 volumio volumio[31765]: info: May 30 09:15:45 volumio volumio[31765]: ---------------------------- MPD announces system playlist update May 30 09:15:45 volumio volumio[31765]: info: Ignoring MPD Status Update May 30 09:15:45 volumio volumio[31765]: info: May 30 09:15:45 volumio volumio[31765]: ---------------------------- MPD announces state update: player May 30 09:15:45 volumio volumio[31765]: info: ControllerMpd::getState May 30 09:15:45 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand status May 30 09:15:45 volumio volumio[31765]: info: ------------------------------ 4ms May 30 09:15:45 volumio volumio[31765]: info: sendMpdCommand status took 1 milliseconds May 30 09:15:45 volumio volumio[31765]: verbose: ControllerMpd::parseState May 30 09:15:45 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 09:15:45 volumio volumio[31765]: info: sendMpdCommand playlistinfo took 0 milliseconds May 30 09:15:45 volumio volumio[31765]: verbose: ControllerMpd::parseTrackInfo May 30 09:15:45 volumio volumio[31765]: info: ControllerMpd::pushState May 30 09:15:45 volumio volumio[31765]: info: CoreCommandRouter::servicePushState May 30 09:15:45 volumio volumio[31765]: info: CorePlayQueue::getTrack 5 May 30 09:15:45 volumio volumio[31765]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1906090&eid=34912269&fmt=6&profile=raw&app_id=539451548&cid=1343785&etsp=1748600106&hmac=-Ls616qXKB73Xl3O_7B6MK5TTpg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1906090&eid=34912269&fmt=6&profile=raw&app_id=539451548&cid=1343785&etsp=1748600106&hmac=-Ls616qXKB73Xl3O_7B6MK5TTpg","trackType":""} May 30 09:15:45 volumio volumio[31765]: verbose: CURRENT POSITION 5 May 30 09:15:45 volumio volumio[31765]: info: CoreStateMachine::syncState stateService play May 30 09:15:45 volumio volumio[31765]: info: CoreStateMachine::syncState currentStatus play May 30 09:15:45 volumio volumio[31765]: info: Received an update from plugin. extracting info from payload May 30 09:15:45 volumio volumio[31765]: info: CoreStateMachine::pushState May 30 09:15:45 volumio volumio[31765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:15:45 volumio volumio[31765]: info: CoreCommandRouter::volumioPushState May 30 09:15:45 volumio volumio[31765]: info: CoreStateMachine::pushState May 30 09:15:45 volumio volumio[31765]: info: CoreCommandRouter::volumioPushState May 30 09:15:45 volumio volumio[31765]: info: ------------------------------ 20ms May 30 09:15:45 volumio volumio[31765]: info: [LastFM] Current track has sufficient metadata: title (Faithless by Default) and artist (Dark Tranquillity) passed on explicitly May 30 09:16:29 volumio volumio[31765]: info: May 30 09:16:29 volumio volumio[31765]: ---------------------------- MPD announces state update: player May 30 09:16:29 volumio volumio[31765]: info: ControllerMpd::getState May 30 09:16:29 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand status May 30 09:16:29 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand clearerror May 30 09:16:29 volumio volumio[31765]: info: sendMpdCommand status took 4 milliseconds May 30 09:16:29 volumio volumio[31765]: verbose: ControllerMpd::parseState May 30 09:16:29 volumio volumio[31765]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 30 09:16:29 volumio volumio[31765]: info: sendMpdCommand clearerror took 2 milliseconds May 30 09:16:29 volumio volumio[31765]: info: sendMpdCommand playlistinfo took 1 milliseconds May 30 09:16:29 volumio volumio[31765]: verbose: ControllerMpd::parseTrackInfo May 30 09:16:29 volumio volumio[31765]: info: ControllerMpd::pushState May 30 09:16:29 volumio volumio[31765]: info: CoreCommandRouter::servicePushState May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::pushState May 30 09:16:29 volumio volumio[31765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:16:29 volumio volumio[31765]: info: CoreCommandRouter::volumioPushState May 30 09:16:29 volumio volumio[31765]: info: CorePlayQueue::getTrack 5 May 30 09:16:29 volumio volumio[31765]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=1906090&eid=34912269&fmt=6&profile=raw&app_id=539451548&cid=1343785&etsp=1748600106&hmac=-Ls616qXKB73Xl3O_7B6MK5TTpg","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=1906090&eid=34912269&fmt=6&profile=raw&app_id=539451548&cid=1343785&etsp=1748600106&hmac=-Ls616qXKB73Xl3O_7B6MK5TTpg","trackType":""} May 30 09:16:29 volumio volumio[31765]: verbose: CURRENT POSITION 5 May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::syncState stateService stop May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::syncState currentStatus play May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::play index undefined May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::pushState May 30 09:16:29 volumio volumio[31765]: info: CorePlayQueue::getTrack 6 May 30 09:16:29 volumio volumio[31765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:16:29 volumio volumio[31765]: info: CoreCommandRouter::volumioPushState May 30 09:16:29 volumio volumio[31765]: info: CorePlayQueue::getTrack 6 May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::startPlaybackTimer May 30 09:16:29 volumio volumio[31765]: info: CorePlayQueue::getTrack 6 May 30 09:16:29 volumio volumio[31765]: info: [1748596589987] ControllerQobuz::clearAddPlayTrack May 30 09:16:29 volumio volumio[31765]: info: ------------------------------ 33ms May 30 09:16:29 volumio volumio[31765]: info: CoreStateMachine::pushState May 30 09:16:29 volumio volumio[31765]: info: CorePlayQueue::getTrack 6 May 30 09:16:29 volumio volumio[31765]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 09:16:29 volumio volumio[31765]: info: CoreCommandRouter::volumioPushState May 30 09:16:32 volumio volumio[31765]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 09:16:32 volumio volumio[31765]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com May 30 09:16:32 volumio volumio[31765]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { May 30 09:16:32 volumio volumio[31765]: errno: -3007, May 30 09:16:32 volumio volumio[31765]: code: 'ENOTFOUND', May 30 09:16:32 volumio volumio[31765]: syscall: 'getaddrinfo', May 30 09:16:32 volumio volumio[31765]: hostname: 'ws.audioscrobbler.com' May 30 09:16:32 volumio volumio[31765]: } May 30 09:16:32 volumio volumio[31765]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 09:16:33 volumio sudo[32484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-30 09:15 May 30 09:16:33 volumio sudo[32484]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"