-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sat 2025-05-03 12:59:00 MSK. -- May 03 12:58:04 volumio go-librespot[1227]: time="2025-05-03T12:58:04+03:00" level=trace msg="sent dealer ping" May 03 12:58:04 volumio go-librespot[1227]: time="2025-05-03T12:58:04+03:00" level=trace msg="received dealer pong" May 03 12:58:16 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 12:58:16 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 12:58:16 volumio volumio[847]: info: Discovery: Getting this device information May 03 12:58:16 volumio volumio[847]: info: CoreCommandRouter::volumioGetState May 03 12:58:16 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:16 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 12:58:23 volumio sshd[10054]: pam_unix(sshd:session): session closed for user volumio May 03 12:58:23 volumio sshd[10053]: pam_unix(sshd:session): session closed for user volumio May 03 12:58:23 volumio sshd[9914]: pam_unix(sshd:session): session closed for user volumio May 03 12:58:23 volumio systemd[1]: session-3.scope: Succeeded. May 03 12:58:23 volumio systemd-logind[607]: Session 3 logged out. Waiting for processes to exit. May 03 12:58:23 volumio systemd[1]: session-1.scope: Succeeded. May 03 12:58:23 volumio systemd[1]: session-4.scope: Succeeded. May 03 12:58:23 volumio systemd-logind[607]: Session 1 logged out. Waiting for processes to exit. May 03 12:58:23 volumio systemd-logind[607]: Session 4 logged out. Waiting for processes to exit. May 03 12:58:23 volumio systemd-logind[607]: Removed session 3. May 03 12:58:23 volumio systemd-logind[607]: Removed session 1. May 03 12:58:23 volumio systemd-logind[607]: Removed session 4. May 03 12:58:30 volumio go-librespot[1227]: time="2025-05-03T12:58:30+03:00" level=trace msg="received accesspoint ping" May 03 12:58:30 volumio go-librespot[1227]: time="2025-05-03T12:58:30+03:00" level=trace msg="received accesspoint pong ack" May 03 12:58:33 volumio volumio[847]: info: CoreCommandRouter::volumioPause May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::pause May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::servicePause May 03 12:58:33 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:33 volumio volumio[847]: info: CoreCommandRouter::servicePause May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd May 03 12:58:33 volumio volumio[847]: info: ControllerMpd::pause May 03 12:58:33 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand pause May 03 12:58:33 volumio volumio[847]: info: May 03 12:58:33 volumio volumio[847]: ---------------------------- MPD announces state update: player May 03 12:58:33 volumio volumio[847]: info: sendMpdCommand pause took 1 milliseconds May 03 12:58:33 volumio volumio[847]: info: ControllerMpd::getState May 03 12:58:33 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status May 03 12:58:33 volumio volumio[847]: info: sendMpdCommand status took 1 milliseconds May 03 12:58:33 volumio volumio[847]: verbose: ControllerMpd::parseState May 03 12:58:33 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 12:58:33 volumio volumio[847]: info: sendMpdCommand playlistinfo took 4 milliseconds May 03 12:58:33 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo May 03 12:58:33 volumio volumio[847]: info: ControllerMpd::pushState May 03 12:58:33 volumio volumio[847]: info: CoreCommandRouter::servicePushState May 03 12:58:33 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:33 volumio volumio[847]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82220,"duration":187,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Oasis","artist":"Gelvetta","album":"Oasis","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-84.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D89519c6a98dc1c7820a5113c7c4c86e1026f2423621eba1e50e14fa01f62600e%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68239745%2F0%2F133420%2Fb7cc7864.64434534.9.43014984%2F320.mp3&ext=.mp3","trackType":"mp3"} May 03 12:58:33 volumio volumio[847]: verbose: CURRENT POSITION 59 May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::syncState stateService pause May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus pause May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::pushState May 03 12:58:33 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 12:58:33 volumio volumio[847]: info: CoreCommandRouter::volumioPushState May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer May 03 12:58:33 volumio volumio[847]: info: ------------------------------ 15ms May 03 12:58:33 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue May 03 12:58:33 volumio volumio[847]: info: CoreStateMachine::getQueue May 03 12:58:33 volumio volumio[847]: info: CorePlayQueue::getQueue May 03 12:58:33 volumio volumio[847]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 03 12:58:33 volumio volumio[847]: SPOTIFY: SPOTIFY VOLUME 100 May 03 12:58:33 volumio volumio[847]: SPOTIFY: VOLUMIO VOLUME 100 May 03 12:58:33 volumio volumio[847]: SPOTIFY: DELTA VOLUME ENOUGH: false May 03 12:58:33 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 03 12:58:33 volumio systemd[1]: Stopping User Manager for UID 1000... May 03 12:58:33 volumio systemd[9921]: Stopped target Default. May 03 12:58:33 volumio systemd[9921]: Stopped target Basic System. May 03 12:58:33 volumio systemd[9921]: Stopped target Paths. May 03 12:58:33 volumio systemd[9921]: Stopped target Timers. May 03 12:58:33 volumio systemd[9921]: Stopped target Sockets. May 03 12:58:33 volumio systemd[9921]: dbus.socket: Succeeded. May 03 12:58:33 volumio systemd[9921]: Closed D-Bus User Message Bus Socket. May 03 12:58:33 volumio systemd[9921]: gpg-agent.socket: Succeeded. May 03 12:58:33 volumio systemd[9921]: Closed GnuPG cryptographic agent and passphrase cache. May 03 12:58:33 volumio systemd[9921]: dirmngr.socket: Succeeded. May 03 12:58:33 volumio systemd[9921]: Closed GnuPG network certificate management daemon. May 03 12:58:33 volumio systemd[9921]: gpg-agent-browser.socket: Succeeded. May 03 12:58:33 volumio systemd[9921]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers). May 03 12:58:33 volumio systemd[9921]: pulseaudio.socket: Succeeded. May 03 12:58:33 volumio systemd[9921]: Closed Sound System. May 03 12:58:33 volumio systemd[9921]: gpg-agent-ssh.socket: Succeeded. May 03 12:58:33 volumio systemd[9921]: Closed GnuPG cryptographic agent (ssh-agent emulation). May 03 12:58:33 volumio systemd[9921]: gpg-agent-extra.socket: Succeeded. May 03 12:58:33 volumio systemd[9921]: Closed GnuPG cryptographic agent and passphrase cache (restricted). May 03 12:58:33 volumio systemd[9921]: Reached target Shutdown. May 03 12:58:33 volumio systemd[9921]: systemd-exit.service: Succeeded. May 03 12:58:33 volumio systemd[9921]: Started Exit the Session. May 03 12:58:33 volumio systemd[9921]: Reached target Exit the Session. May 03 12:58:33 volumio systemd[1]: user@1000.service: Succeeded. May 03 12:58:33 volumio systemd[1]: Stopped User Manager for UID 1000. May 03 12:58:33 volumio systemd[1]: Stopping User Runtime Directory /run/user/1000... May 03 12:58:33 volumio systemd[1]: run-user-1000.mount: Succeeded. May 03 12:58:33 volumio systemd[1]: user-runtime-dir@1000.service: Succeeded. May 03 12:58:33 volumio systemd[1]: Stopped User Runtime Directory /run/user/1000. May 03 12:58:33 volumio systemd[1]: Removed slice User Slice of UID 1000. May 03 12:58:34 volumio volumio[847]: info: CoreCommandRouter::volumioPlay May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::play index undefined May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 12:58:34 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer May 03 12:58:34 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd May 03 12:58:34 volumio volumio[847]: info: ControllerMpd::resume May 03 12:58:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play May 03 12:58:34 volumio go-librespot[1227]: time="2025-05-03T12:58:34+03:00" level=trace msg="sent dealer ping" May 03 12:58:34 volumio volumio[847]: info: May 03 12:58:34 volumio volumio[847]: ---------------------------- MPD announces state update: player May 03 12:58:34 volumio volumio[847]: info: sendMpdCommand play took 293 milliseconds May 03 12:58:34 volumio volumio[847]: info: ControllerMpd::getState May 03 12:58:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status May 03 12:58:34 volumio volumio[847]: info: sendMpdCommand status took 1 milliseconds May 03 12:58:34 volumio volumio[847]: verbose: ControllerMpd::parseState May 03 12:58:34 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 12:58:34 volumio volumio[847]: info: sendMpdCommand playlistinfo took 1 milliseconds May 03 12:58:34 volumio go-librespot[1227]: time="2025-05-03T12:58:34+03:00" level=trace msg="received dealer pong" May 03 12:58:34 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo May 03 12:58:34 volumio volumio[847]: info: ControllerMpd::pushState May 03 12:58:34 volumio volumio[847]: info: CoreCommandRouter::servicePushState May 03 12:58:34 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:34 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82220,"duration":187,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Oasis","artist":"Gelvetta","album":"Oasis","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-84.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D89519c6a98dc1c7820a5113c7c4c86e1026f2423621eba1e50e14fa01f62600e%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68239745%2F0%2F133420%2Fb7cc7864.64434534.9.43014984%2F320.mp3&ext=.mp3","trackType":"mp3"} May 03 12:58:34 volumio volumio[847]: verbose: CURRENT POSITION 59 May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::syncState stateService play May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus pause May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::pushState May 03 12:58:34 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 12:58:34 volumio volumio[847]: info: CoreCommandRouter::volumioPushState May 03 12:58:34 volumio volumio[847]: info: ------------------------------ 11ms May 03 12:58:34 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue May 03 12:58:34 volumio volumio[847]: info: CoreStateMachine::getQueue May 03 12:58:34 volumio volumio[847]: info: CorePlayQueue::getQueue May 03 12:58:34 volumio volumio[847]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 03 12:58:34 volumio volumio[847]: SPOTIFY: SPOTIFY VOLUME 100 May 03 12:58:34 volumio volumio[847]: SPOTIFY: VOLUMIO VOLUME 100 May 03 12:58:34 volumio volumio[847]: SPOTIFY: DELTA VOLUME ENOUGH: false May 03 12:58:34 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 0 seconds. May 03 12:58:34 volumio volumio[847]: info: peppy_screensaver: Start PeppyMeter May 03 12:58:42 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue May 03 12:58:42 volumio volumio[847]: info: CoreStateMachine::getQueue May 03 12:58:42 volumio volumio[847]: info: CorePlayQueue::getQueue May 03 12:58:43 volumio volumio[847]: info: CoreCommandRouter::volumioPlay May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::play index 53 May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::stop May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::stPlaybackTimer May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::updateTrackBlock May 03 12:58:43 volumio volumio[847]: info: CorePlayQueue::getTrackBlock May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::pushState May 03 12:58:43 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:43 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 12:58:43 volumio volumio[847]: info: CoreCommandRouter::volumioPushState May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::serviceStop May 03 12:58:43 volumio volumio[847]: info: CorePlayQueue::getTrack 59 May 03 12:58:43 volumio volumio[847]: info: CoreCommandRouter::serviceStop May 03 12:58:43 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd May 03 12:58:43 volumio volumio[847]: info: ControllerMpd::stop May 03 12:58:43 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop May 03 12:58:43 volumio volumio[847]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 03 12:58:43 volumio volumio[847]: SPOTIFY: SPOTIFY VOLUME 100 May 03 12:58:43 volumio volumio[847]: SPOTIFY: VOLUMIO VOLUME 100 May 03 12:58:43 volumio volumio[847]: SPOTIFY: DELTA VOLUME ENOUGH: false May 03 12:58:44 volumio volumio[847]: info: May 03 12:58:44 volumio volumio[847]: ---------------------------- MPD announces state update: player May 03 12:58:44 volumio volumio[847]: info: sendMpdCommand stop took 32 milliseconds May 03 12:58:44 volumio volumio[847]: info: ControllerMpd::getState May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status May 03 12:58:44 volumio volumio[847]: info: CoreStateMachine::play index undefined May 03 12:58:44 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 12:58:44 volumio volumio[847]: info: CorePlayQueue::getTrack 53 May 03 12:58:44 volumio volumio[847]: info: CoreStateMachine::startPlaybackTimer May 03 12:58:44 volumio volumio[847]: info: CorePlayQueue::getTrack 53 May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand stop May 03 12:58:44 volumio volumio[847]: info: sendMpdCommand status took 5 milliseconds May 03 12:58:44 volumio volumio[847]: info: sendMpdCommand stop took 2 milliseconds May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::parseState May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand clear May 03 12:58:44 volumio volumio[847]: info: May 03 12:58:44 volumio volumio[847]: ---------------------------- MPD announces system playlist update May 03 12:58:44 volumio volumio[847]: info: Ignoring MPD Status Update May 03 12:58:44 volumio volumio[847]: info: sendMpdCommand playlistinfo took 4 milliseconds May 03 12:58:44 volumio volumio[847]: info: sendMpdCommand clear took 4 milliseconds May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo May 03 12:58:44 volumio volumio[847]: info: ControllerMpd::pushState May 03 12:58:44 volumio volumio[847]: info: CoreCommandRouter::servicePushState May 03 12:58:44 volumio volumio[847]: info: CoreStateMachine::pushState May 03 12:58:44 volumio volumio[847]: info: CorePlayQueue::getTrack 53 May 03 12:58:44 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 12:58:44 volumio volumio[847]: info: CoreCommandRouter::volumioPushState May 03 12:58:44 volumio volumio[847]: info: CorePlayQueue::getTrack 53 May 03 12:58:44 volumio volumio[847]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd May 03 12:58:44 volumio volumio[847]: info: ------------------------------ 26ms May 03 12:58:44 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 03 12:58:44 volumio volumio[847]: error: updateQueue error: null May 03 12:58:44 volumio volumio[847]: info: ------------------------------ 25ms May 03 12:58:44 volumio volumio[847]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 03 12:58:44 volumio volumio[847]: SPOTIFY: SPOTIFY VOLUME 100 May 03 12:58:44 volumio volumio[847]: SPOTIFY: VOLUMIO VOLUME 100 May 03 12:58:44 volumio volumio[847]: SPOTIFY: DELTA VOLUME ENOUGH: false May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-108.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D347cf88609f11993e0f8082a14f9a4c3a14159082ccfa91b19472b92890e2927%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D682428bd%2F51167%2F137299%2F7d3e57ce.147324031.9.97506249%2F320.mp3&ext=.mp3" May 03 12:58:44 volumio volumio[847]: info: May 03 12:58:44 volumio volumio[847]: ---------------------------- MPD announces system playlist update May 03 12:58:44 volumio volumio[847]: info: Ignoring MPD Status Update May 03 12:58:44 volumio volumio[847]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-108.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D347cf88609f11993e0f8082a14f9a4c3a14159082ccfa91b19472b92890e2927%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D682428bd%2F51167%2F137299%2F7d3e57ce.147324031.9.97506249%2F320.mp3&ext=.mp3" took 1 milliseconds May 03 12:58:44 volumio volumio[847]: verbose: MPD COMMAND [object Object] May 03 12:58:44 volumio volumio[847]: verbose: MPD COMMAND [object Object] May 03 12:58:44 volumio volumio[847]: info: May 03 12:58:44 volumio volumio[847]: ---------------------------- MPD announces system playlist update May 03 12:58:44 volumio volumio[847]: info: Ignoring MPD Status Update May 03 12:58:44 volumio volumio[847]: info: ------------------------------ 3ms May 03 12:58:44 volumio volumio[847]: info: CoreStateMachine::setConsumeUpdateService mpd May 03 12:58:44 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand play May 03 12:58:44 volumio volumio[847]: info: ------------------------------ 2ms May 03 12:58:44 volumio volumio[847]: info: sendMpdCommand play took 1 milliseconds May 03 12:58:45 volumio volumio[847]: info: May 03 12:58:45 volumio volumio[847]: ---------------------------- MPD announces state update: player May 03 12:58:45 volumio volumio[847]: info: ControllerMpd::getState May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status May 03 12:58:45 volumio volumio[847]: info: May 03 12:58:45 volumio volumio[847]: ---------------------------- MPD announces state update: player May 03 12:58:45 volumio volumio[847]: info: sendMpdCommand status took 289 milliseconds May 03 12:58:45 volumio volumio[847]: info: ControllerMpd::getState May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::parseState May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 12:58:45 volumio volumio[847]: info: sendMpdCommand status took 1 milliseconds May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::parseState May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 12:58:45 volumio volumio[847]: info: sendMpdCommand playlistinfo took 2 milliseconds May 03 12:58:45 volumio volumio[847]: info: sendMpdCommand playlistinfo took 1 milliseconds May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo May 03 12:58:45 volumio volumio[847]: verbose: ControllerMpd::parseTrackInfo May 03 12:58:45 volumio volumio[847]: info: ControllerMpd::pushState May 03 12:58:45 volumio volumio[847]: info: CoreCommandRouter::servicePushState May 03 12:58:45 volumio volumio[847]: info: CorePlayQueue::getTrack 53 May 03 12:58:45 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":329,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tataki","artist":"Argy","album":"New World","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-108.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D347cf88609f11993e0f8082a14f9a4c3a14159082ccfa91b19472b92890e2927%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D682428bd%2F51167%2F137299%2F7d3e57ce.147324031.9.97506249%2F320.mp3&ext=.mp3","trackType":"mp3"} May 03 12:58:45 volumio volumio[847]: verbose: CURRENT POSITION 53 May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::syncState stateService play May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop May 03 12:58:45 volumio volumio[847]: info: ControllerMpd::pushState May 03 12:58:45 volumio volumio[847]: info: CoreCommandRouter::servicePushState May 03 12:58:45 volumio volumio[847]: info: CorePlayQueue::getTrack 53 May 03 12:58:45 volumio volumio[847]: verbose: STATE SERVICE {"status":"play","position":0,"seek":654,"duration":329,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Tataki","artist":"Argy","album":"New World","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-108.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D347cf88609f11993e0f8082a14f9a4c3a14159082ccfa91b19472b92890e2927%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D682428bd%2F51167%2F137299%2F7d3e57ce.147324031.9.97506249%2F320.mp3&ext=.mp3","trackType":"mp3"} May 03 12:58:45 volumio volumio[847]: verbose: CURRENT POSITION 53 May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::syncState stateService play May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus play May 03 12:58:45 volumio volumio[847]: info: Received an update from plugin. extracting info from payload May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::pushState May 03 12:58:45 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 12:58:45 volumio volumio[847]: info: CoreCommandRouter::volumioPushState May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::pushState May 03 12:58:45 volumio volumio[847]: info: CoreCommandRouter::volumioPushState May 03 12:58:45 volumio volumio[847]: info: ------------------------------ 300ms May 03 12:58:45 volumio volumio[847]: info: ------------------------------ 17ms May 03 12:58:45 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::getQueue May 03 12:58:45 volumio volumio[847]: info: CorePlayQueue::getQueue May 03 12:58:45 volumio volumio[847]: info: CoreCommandRouter::volumioGetQueue May 03 12:58:45 volumio volumio[847]: info: CoreStateMachine::getQueue May 03 12:58:45 volumio volumio[847]: info: CorePlayQueue::getQueue May 03 12:58:45 volumio volumio[847]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 03 12:58:45 volumio volumio[847]: SPOTIFY: SPOTIFY VOLUME 100 May 03 12:58:45 volumio volumio[847]: SPOTIFY: VOLUMIO VOLUME 100 May 03 12:58:45 volumio volumio[847]: SPOTIFY: DELTA VOLUME ENOUGH: false May 03 12:58:45 volumio volumio[847]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 03 12:58:45 volumio volumio[847]: SPOTIFY: SPOTIFY VOLUME 100 May 03 12:58:45 volumio volumio[847]: SPOTIFY: VOLUMIO VOLUME 100 May 03 12:58:45 volumio volumio[847]: SPOTIFY: DELTA VOLUME ENOUGH: false May 03 12:58:46 volumio volumio[847]: info: touch_display: Setting screensaver timeout to 0 seconds. May 03 12:58:46 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 12:58:46 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 12:58:46 volumio volumio[847]: info: Discovery: Getting this device information May 03 12:58:46 volumio volumio[847]: info: CoreCommandRouter::volumioGetState May 03 12:58:46 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 12:58:49 volumio volumio[847]: 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: 11 May 03 12:58:49 volumio volumio[847]: info: CoreCommandRouter::volumioGetState May 03 12:58:58 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 03 12:58:58 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 03 12:58:58 volumio volumio[847]: info: Discovery: Getting this device information May 03 12:58:58 volumio volumio[847]: info: CoreCommandRouter::volumioGetState May 03 12:58:58 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 03 12:58:59 volumio volumio[847]: verbose: New Socket.io Connection to 192.168.68.183:3000 from 192.168.68.139 UA: Dart/3.7 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 03 12:59:00 volumio volumio[847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 12:59:00 volumio volumio[847]: Error: read ECONNRESET May 03 12:59:00 volumio volumio[847]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { May 03 12:59:00 volumio volumio[847]: errno: -104, May 03 12:59:00 volumio volumio[847]: code: 'ECONNRESET', May 03 12:59:00 volumio volumio[847]: syscall: 'read' May 03 12:59:00 volumio volumio[847]: } May 03 12:59:00 volumio volumio[847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 12:59:00 volumio sudo[10306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-03 12:58 May 03 12:59:00 volumio sudo[10306]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"