-- Logs begin at Tue 2025-01-14 11:52:29 CST, end at Tue 2025-01-14 14:40:37 CST. -- Jan 14 14:39:24 volumio volumio[1199]: info: CorePlayQueue::getTrack 4 Jan 14 14:39:24 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:24 volumio volumio[1199]: info: Prefetching next song Jan 14 14:39:24 volumio volumio[1199]: info: [1736836764490] ControllerVolusonic::prefetch Jan 14 14:39:24 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand add "https://music.wkczy.com:1443/rest/stream.view?id=f92e34cee90c2e186ff4e8937966e631&format=raw&u=wk224&t=1fe253d10e596efd339e50c46dbb84b4&s=l92vM&v=1.10.2&f=json&c=volusonic" Jan 14 14:39:24 volumio volumio[1199]: info: Jan 14 14:39:24 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Jan 14 14:39:24 volumio volumio[1199]: info: Ignoring MPD Status Update Jan 14 14:39:24 volumio volumio[1199]: info: sendMpdCommand add "https://music.wkczy.com:1443/rest/stream.view?id=f92e34cee90c2e186ff4e8937966e631&format=raw&u=wk224&t=1fe253d10e596efd339e50c46dbb84b4&s=l92vM&v=1.10.2&f=json&c=volusonic" took 2 milliseconds Jan 14 14:39:24 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 14 14:39:24 volumio volumio[1199]: info: ------------------------------ 2ms Jan 14 14:39:24 volumio volumio[1199]: info: sendMpdCommand consume 1 took 2 milliseconds Jan 14 14:39:28 volumio volumio[1199]: info: CoreStateMachine::startPlaybackTimer Jan 14 14:39:28 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:29 volumio volumio[1199]: info: CoreStateMachine::pushState Jan 14 14:39:29 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::volumioGetQueue Jan 14 14:39:29 volumio volumio[1199]: info: CoreStateMachine::getQueue Jan 14 14:39:29 volumio volumio[1199]: info: CorePlayQueue::getQueue Jan 14 14:39:29 volumio volumio[1199]: info: [LastFM] Current track has sufficient metadata: title (Speechless) and artist (Dan + Shay) passed on explicitly Jan 14 14:39:29 volumio volumio[1199]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Speechless","url":"https://www.last.fm/music/Dan+%252B+Shay/_/Speechless","duration":"213000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"140181","playcount":"1049428","artist":{"name":"Dan + Shay","mbid":"6a2d48d4-25c0-483d-b135-680af589a4ae","url":"https://www.last.fm/music/Dan+%252B+Shay"},"album":{"artist":"Dan + Shay","title":"Dan + Shay","url":"https://www.last.fm/music/Dan+%252B+Shay/Dan+%252B+Shay","image":[{"#":"https://lastfm.freetls.fastly.net/i/u/34s/9107ce3902ed9a46742c356abf84d169.png","@":{"size":"small"}},{"#":"https://lastfm.freetls.fastly.net/i/u/64s/9107ce3902ed9a46742c356abf84d169.png","@":{"size":"medium"}},{"#":"https://lastfm.freetls.fastly.net/i/u/174s/9107ce3902ed9a46742c356abf84d169.png","@":{"size":"large"}},{"#":"https://lastfm.freetls.fastly.net/i/u/300x300/9107ce3902ed9a46742c356abf84d169.png","@":{"size":"extralarge"}}]},"userplaycount":"0","userloved":"0","toptags":{"tag":[{"name":"2010s","url":"https://www.last.fm/tag/2010s"},{"name":"country","url":"https://www.last.fm/tag/country"},{"name":"vocal harmonies","url":"https://www.last.fm/tag/vocal+harmonies"},{"name":"Ballad","url":"https://www.last.fm/tag/Ballad"},{"name":"fallon","url":"https://www.last.fm/tag/fallon"}]}}} Jan 14 14:39:29 volumio volumio[1199]: info: Jan 14 14:39:29 volumio volumio[1199]: ---------------------------- MPD announces system playlist update Jan 14 14:39:29 volumio volumio[1199]: info: Ignoring MPD Status Update Jan 14 14:39:29 volumio volumio[1199]: info: [1736836769927] ControllerVolusonic: MPD player state update Jan 14 14:39:29 volumio volumio[1199]: info: ControllerMpd::getState Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Jan 14 14:39:29 volumio volumio[1199]: info: Jan 14 14:39:29 volumio volumio[1199]: ---------------------------- MPD announces state update: player Jan 14 14:39:29 volumio volumio[1199]: info: ControllerMpd::getState Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand status Jan 14 14:39:29 volumio volumio[1199]: info: sendMpdCommand status took 3 milliseconds Jan 14 14:39:29 volumio volumio[1199]: info: ------------------------------ 4ms Jan 14 14:39:29 volumio volumio[1199]: info: sendMpdCommand status took 1 milliseconds Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::parseState Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::parseState Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 14 14:39:29 volumio volumio[1199]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Jan 14 14:39:29 volumio volumio[1199]: verbose: ControllerMpd::parseTrackInfo Jan 14 14:39:29 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:29 volumio volumio[1199]: info: [1736836769936] ControllerVolusonic::pushState Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Jan 14 14:39:29 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:29 volumio volumio[1199]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Speechless","artist":"Dan + Shay","album":"Romantic Date Night Songs","uri":"https://music.wkczy.com:1443/rest/stream.view?id=f92e34cee90c2e186ff4e8937966e631&format=raw&u=wk224&t=1fe253d10e596efd339e50c46dbb84b4&s=l92vM&v=1.10.2&f=json&c=volusonic","trackType":""} Jan 14 14:39:29 volumio volumio[1199]: verbose: CURRENT POSITION 5 Jan 14 14:39:29 volumio volumio[1199]: info: CoreStateMachine::syncState stateService play Jan 14 14:39:29 volumio volumio[1199]: info: CoreStateMachine::syncState currentStatus play Jan 14 14:39:29 volumio volumio[1199]: info: Received an update from plugin. extracting info from payload Jan 14 14:39:29 volumio volumio[1199]: info: CoreStateMachine::pushState Jan 14 14:39:29 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Jan 14 14:39:29 volumio volumio[1199]: info: CoreStateMachine::pushState Jan 14 14:39:29 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::volumioPushState Jan 14 14:39:29 volumio volumio[1199]: info: ControllerMpd::pushState Jan 14 14:39:29 volumio volumio[1199]: info: CoreCommandRouter::servicePushState Jan 14 14:39:29 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:39:29 volumio volumio[1199]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Jan 14 14:39:29 volumio volumio[1199]: info: ------------------------------ 20ms Jan 14 14:39:30 volumio volumio[1199]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 14 14:39:30 volumio volumio[1199]: info: CoreCommandRouter::volumioGetState Jan 14 14:39:30 volumio volumio[1199]: info: CorePlayQueue::getTrack 5 Jan 14 14:40:36 volumio volumio[1199]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:40:36 volumio volumio[1199]: Error: connect ETIMEDOUT 130.211.19.189:80 Jan 14 14:40:36 volumio volumio[1199]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 14 14:40:36 volumio volumio[1199]: errno: -110, Jan 14 14:40:36 volumio volumio[1199]: code: 'ETIMEDOUT', Jan 14 14:40:36 volumio volumio[1199]: syscall: 'connect', Jan 14 14:40:36 volumio volumio[1199]: address: '130.211.19.189', Jan 14 14:40:36 volumio volumio[1199]: port: 80 Jan 14 14:40:36 volumio volumio[1199]: } Jan 14 14:40:36 volumio volumio[1199]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 14:40:37 volumio sudo[18750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-14 14:39 Jan 14 14:40:37 volumio sudo[18750]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"