-- Logs begin at Mon 2025-09-08 13:33:19 EEST, end at Mon 2025-09-08 16:13:27 EEST. -- Sep 08 16:12:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 16:12:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 16:12:00 volumio volumio[981]: info: Discovery: Getting this device information Sep 08 16:12:00 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 08 16:12:00 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 16:12:01 volumio volumio[981]: verbose: New Socket.io Connection to 192.168.0.30:3000 from 192.168.0.13 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Sep 08 16:12:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 16:12:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 16:12:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 16:12:04 volumio volumio[981]: info: Discovery: Getting this device information Sep 08 16:12:04 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 08 16:12:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 16:12:04 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:07 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::serviceStop Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::serviceStop Sep 08 16:12:07 volumio volumio[981]: info: Spotify Stop Sep 08 16:12:07 volumio volumio[981]: SPOTIFY: SPOTIFY STOP Sep 08 16:12:07 volumio volumio[981]: SPOTIFY: {"status":"pause","position":0,"title":"Fourplay - Love TKO","artist":"Smooth Jazz Wave","album":null,"albumart":"/albumart?cacheid=513&web=Smooth%20Jazz%20Wave//small","uri":"http://server.webnetradio.net:5007/stream","trackType":"webradio","seek":16476,"duration":0,"samplerate":"","bitdepth":"","channels":2,"bitrate":"128 Kbps","random":null,"repeat":true,"repeatSingle":false,"consume":true,"volume":90,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Sep 08 16:12:07 volumio volumio[981]: info: Sending Spotify command to local API: /player/pause Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:07 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:07 volumio volumio[981]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99616827 Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:07 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:07 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:07 volumio volumio[981]: info: [1757337127975] ControllerWebradio::clearAddPlayTrack Sep 08 16:12:07 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:07 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:12:07 volumio go-librespot[5473]: time="2025-09-08T16:12:07+03:00" level=debug msg="pause track at 200455ms" Sep 08 16:12:08 volumio go-librespot[5473]: time="2025-09-08T16:12:08+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 08 16:12:08 volumio go-librespot[5473]: time="2025-09-08T16:12:08+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 08 16:12:08 volumio go-librespot[5473]: time="2025-09-08T16:12:08+03:00" level=trace msg="emitting websocket event: paused" Sep 08 16:12:08 volumio volumio[981]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:43yWmVsrAcgWN40HjKt4Qu","play_origin":"go-librespot"}} Sep 08 16:12:08 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:12:08 volumio volumio[981]: SPOTIFY: {"status":"pause","service":"spop","title":"You Better Hide","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:43yWmVsrAcgWN40HjKt4Qu","trackType":"spotify","seek":200001,"duration":248,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:12:08 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:12:08 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:08 volumio volumio[981]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Sep 08 16:12:08 volumio volumio[981]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:43yWmVsrAcgWN40HjKt4Qu","play_origin":"go-librespot"}} Sep 08 16:12:08 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:12:08 volumio volumio[981]: SPOTIFY: {"status":"pause","service":"spop","title":"You Better Hide","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:43yWmVsrAcgWN40HjKt4Qu","trackType":"spotify","seek":200001,"duration":248,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:12:08 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:12:08 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:08 volumio volumio[981]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Sep 08 16:12:11 volumio volumio[981]: verbose: New Socket.io Connection to 192.168.0.30:3000 from 192.168.0.13 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Sep 08 16:12:13 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:13 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:13 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:13 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:13 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:13 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:13 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:13 volumio volumio[981]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99571737 Sep 08 16:12:13 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 08 16:12:13 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:13 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:13 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:13 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:13 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:13 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:13 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:13 volumio volumio[981]: info: [1757337133279] ControllerWebradio::clearAddPlayTrack Sep 08 16:12:13 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:13 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:13 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:13 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:13 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:13 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:13 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:15 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:15 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:15 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:15 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:15 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:15 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:15 volumio volumio[981]: info: [1757337135209] ControllerWebradio::clearAddPlayTrack Sep 08 16:12:15 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:15 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:15 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:15 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:15 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:15 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:15 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:17 volumio go-librespot[5473]: time="2025-09-08T16:12:17+03:00" level=trace msg="sent dealer ping" Sep 08 16:12:17 volumio go-librespot[5473]: time="2025-09-08T16:12:17+03:00" level=trace msg="received dealer pong" Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:21 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:22 volumio volumio[981]: error: error Sep 08 16:12:23 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 08 16:12:23 volumio volumio[981]: info: Getting webradio selection Sep 08 16:12:23 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:24 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:24 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:24 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:24 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:24 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:24 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:24 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:24 volumio volumio[981]: info: Adding Item to queue: http://icecast.radiofrance.fr/franceculture-midfi.mp3 Sep 08 16:12:24 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 08 16:12:24 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:24 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:24 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:24 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:24 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:24 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:24 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:24 volumio volumio[981]: info: [1757337144792] ControllerWebradio::clearAddPlayTrack Sep 08 16:12:24 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:24 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:24 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:24 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:24 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:24 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:24 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:30 volumio volumio[981]: error: error Sep 08 16:12:30 volumio volumio[981]: error: error Sep 08 16:12:30 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 16:12:30 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 16:12:30 volumio volumio[981]: info: Discovery: Getting this device information Sep 08 16:12:30 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 08 16:12:30 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:30 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 16:12:30 volumio volumio[981]: error: error Sep 08 16:12:30 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: error: error Sep 08 16:12:31 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: swarmstation , handleBrowseUri Sep 08 16:12:31 volumio volumio[981]: info: [1757337151863] [Swarmstation] handleBrowseUri curUri: swarmstation Sep 08 16:12:31 volumio volumio[981]: info: [1757337151863] [Swarmstation] getRadioContent url: swarmstation Sep 08 16:12:31 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:32 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:32 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:32 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:32 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:32 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:32 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:32 volumio volumio[981]: info: Adding Item to queue: webrns/1 Sep 08 16:12:32 volumio volumio[981]: info: Exploding uri webrns/1 in service swarmstation Sep 08 16:12:32 volumio volumio[981]: info: [1757337152535] [Swarmstation] explodeUri: webrns/1 Sep 08 16:12:32 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:32 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:32 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:32 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:32 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:32 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:32 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:32 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:32 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:32 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:32 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:32 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:32 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:32 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:33 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:33 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:33 volumio volumio[981]: info: Adding Item to queue: webrns/0 Sep 08 16:12:33 volumio volumio[981]: info: Exploding uri webrns/0 in service swarmstation Sep 08 16:12:33 volumio volumio[981]: info: [1757337153316] [Swarmstation] explodeUri: webrns/0 Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:33 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:33 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:33 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:33 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:33 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:33 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:33 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:33 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:33 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:33 volumio volumio[981]: info: Adding Item to queue: webrns/0 Sep 08 16:12:33 volumio volumio[981]: info: Using cached record of: webrns/0 Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:33 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:33 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:33 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:33 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:33 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:33 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:33 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:33 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:33 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:33 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 16:12:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 16:12:34 volumio volumio[981]: info: Discovery: Getting this device information Sep 08 16:12:34 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 08 16:12:34 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:34 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 16:12:35 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:35 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:35 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:35 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:35 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:35 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:35 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:35 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:35 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:35 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:35 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:35 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:35 volumio volumio[981]: info: Adding Item to queue: webrns/0 Sep 08 16:12:35 volumio volumio[981]: info: Using cached record of: webrns/0 Sep 08 16:12:35 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:35 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:35 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:35 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:35 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:35 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:35 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:35 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:35 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:35 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:35 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:42 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:42 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:42 volumio volumio[981]: info: Adding Item to queue: webrns/0 Sep 08 16:12:42 volumio volumio[981]: info: Using cached record of: webrns/0 Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:42 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:42 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:42 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:42 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:42 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:42 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:42 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:42 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:42 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:42 volumio volumio[981]: info: Adding Item to queue: webrns/0 Sep 08 16:12:42 volumio volumio[981]: info: Using cached record of: webrns/0 Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:42 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:42 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:42 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:42 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:42 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:42 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:42 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:42 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:42 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:42 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:47 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 16:12:47 volumio volumio[981]: info: CURURI: playlists Sep 08 16:12:47 volumio volumio[981]: info: Listing playlists Sep 08 16:12:47 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:47 volumio go-librespot[5473]: time="2025-09-08T16:12:47+03:00" level=trace msg="sent dealer ping" Sep 08 16:12:47 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 08 16:12:47 volumio volumio[981]: info: CURURI: playlists/011 Sep 08 16:12:47 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:47 volumio go-librespot[5473]: time="2025-09-08T16:12:47+03:00" level=trace msg="received dealer pong" Sep 08 16:12:48 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:48 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:48 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:12:48 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:48 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:12:48 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:12:48 volumio volumio[981]: info: Preload queue cleared Sep 08 16:12:48 volumio volumio[981]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1490590 Sep 08 16:12:48 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 08 16:12:48 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:12:48 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:12:48 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:12:48 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:48 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:48 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:48 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:48 volumio volumio[981]: info: [1757337168479] ControllerWebradio::clearAddPlayTrack Sep 08 16:12:48 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:48 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:48 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:48 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:48 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:48 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:48 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:48 volumio go-librespot[5473]: time="2025-09-08T16:12:48+03:00" level=trace msg="received accesspoint ping" Sep 08 16:12:48 volumio go-librespot[5473]: time="2025-09-08T16:12:48+03:00" level=trace msg="received accesspoint pong ack" Sep 08 16:12:50 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:12:50 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:12:50 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:12:50 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:50 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:12:50 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:12:50 volumio volumio[981]: info: [1757337170010] ControllerWebradio::clearAddPlayTrack Sep 08 16:12:50 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:12:50 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:50 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:50 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:50 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:12:50 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:12:50 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:03 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:13:03 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:03 volumio volumio[981]: info: [1757337183351] ControllerWebradio::clearAddPlayTrack Sep 08 16:13:03 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:03 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:13:03 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:03 volumio volumio[981]: info: [1757337183568] ControllerWebradio::clearAddPlayTrack Sep 08 16:13:03 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:03 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:03 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:13:03 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:03 volumio volumio[981]: info: [1757337183764] ControllerWebradio::clearAddPlayTrack Sep 08 16:13:03 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:03 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:03 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 08 16:13:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 08 16:13:04 volumio volumio[981]: info: Discovery: Getting this device information Sep 08 16:13:04 volumio volumio[981]: info: CoreCommandRouter::volumioGetState Sep 08 16:13:04 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:04 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 08 16:13:07 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:13:07 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:13:07 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:07 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:13:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:07 volumio volumio[981]: info: [1757337187603] ControllerWebradio::clearAddPlayTrack Sep 08 16:13:07 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:13:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:07 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:13:07 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:13:07 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:07 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:13:07 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:07 volumio volumio[981]: info: [1757337187931] ControllerWebradio::clearAddPlayTrack Sep 08 16:13:07 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:13:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:07 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:07 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:08 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:13:08 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:13:08 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:08 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:08 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:13:08 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:08 volumio volumio[981]: info: [1757337188249] ControllerWebradio::clearAddPlayTrack Sep 08 16:13:08 volumio volumio[981]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 16:13:08 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:08 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:08 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:08 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:08 volumio volumio[981]: error: MPD error: Error: This socket has been ended by the other party Sep 08 16:13:08 volumio volumio[981]: error: This socket has been ended by the other party {"code":"EPIPE"} Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:12 volumio volumio[981]: error: error Sep 08 16:13:13 volumio volumio[981]: info: Preload queue cleared Sep 08 16:13:13 volumio volumio[981]: info: Preload queue cleared Sep 08 16:13:13 volumio volumio[981]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::ClearQueue Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:13 volumio volumio[981]: info: CorePlayQueue::clearPlayQueue Sep 08 16:13:13 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:13:13 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::addQueueItems Sep 08 16:13:13 volumio volumio[981]: info: CorePlayQueue::addQueueItems Sep 08 16:13:13 volumio volumio[981]: info: Preload queue cleared Sep 08 16:13:13 volumio volumio[981]: info: Adding Item to queue: spotify:track:4kfAJUz1aLUOSmhq1ljP8s Sep 08 16:13:13 volumio volumio[981]: info: Using cached record of: spotify:track:4kfAJUz1aLUOSmhq1ljP8s Sep 08 16:13:13 volumio volumio[981]: info: CoreCommandRouter::volumioPushQueue Sep 08 16:13:13 volumio volumio[981]: info: CorePlayQueue::saveQueue Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::updateTrackBlock Sep 08 16:13:13 volumio volumio[981]: info: CorePlayQueue::getTrackBlock Sep 08 16:13:13 volumio volumio[981]: info: CoreCommandRouter::volumioPlay Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::play index 0 Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::stop Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::play index undefined Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 16:13:13 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:13 volumio volumio[981]: info: CoreStateMachine::startPlaybackTimer Sep 08 16:13:13 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:13 volumio volumio[981]: info: [1757337193674] ControllerSpotify::clearAddPlayTrack Sep 08 16:13:13 volumio volumio[981]: info: Sending Spotify command with payload to local API: /player/play Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=debug msg="resolved context of track" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=trace msg="emitting websocket event: will_play" Sep 08 16:13:13 volumio volumio[981]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","play_origin":"go-librespot"}} Sep 08 16:13:13 volumio volumio[981]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","play_origin":"go-librespot"}} Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=debug msg="selected format OGG_VORBIS_160 (ea54c1501dc7db7ea623fcaca5b67ba78cc8f803)" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=debug msg="requested aes key for file ea54c1501dc7db7ea623fcaca5b67ba78cc8f803, gid: 4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:13 volumio go-librespot[5473]: time="2025-09-08T16:13:13+03:00" level=trace msg="found 2 cdn urls" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=debug msg="fetched first chunk of 9, total size is 4219624 bytes" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=info msg="loaded track \"Kiss In Blue\" (paused: false, position: 0ms, duration: 213800ms, prefetched: false)" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=debug msg="fetched chunk 1/8, size: 524288" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=trace msg="scheduling prefetch in 184s" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=trace msg="emitting websocket event: metadata" Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","name":"Kiss In Blue","artist_names":["Yello","Heidi Happy"],"album_name":"Touch Yello (Deluxe)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","position":0,"duration":213800,"release_date":"year:2009 month:1 day:1","track_number":9,"disc_number":1}} Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","name":"Kiss In Blue","artist_names":["Yello","Heidi Happy"],"album_name":"Touch Yello (Deluxe)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","position":0,"duration":213800,"release_date":"year:2009 month:1 day:1","track_number":9,"disc_number":1}} Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=debug msg="fetched chunk 2/8, size: 524288" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=trace msg="emitting websocket event: playing" Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","resume":false,"play_origin":"go-librespot"}} Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: verbose: CURRENT POSITION 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus stop Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","resume":false,"play_origin":"go-librespot"}} Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: verbose: CURRENT POSITION 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Sep 08 16:13:14 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:13:14 volumio go-librespot[5473]: time="2025-09-08T16:13:14+03:00" level=debug msg="fetched chunk 3/8, size: 524288" uri="spotify:track:4kfAJUz1aLUOSmhq1ljP8s" Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: verbose: CURRENT POSITION 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Sep 08 16:13:14 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:14 volumio volumio[981]: verbose: CURRENT POSITION 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState stateService play Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus play Sep 08 16:13:14 volumio volumio[981]: info: Received an update from plugin. extracting info from payload Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:14 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:14 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:14 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:13:14 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:13:16 volumio volumio[981]: info: CoreCommandRouter::volumioPause Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::pause Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::servicePause Sep 08 16:13:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:16 volumio volumio[981]: info: CoreCommandRouter::servicePause Sep 08 16:13:16 volumio volumio[981]: info: Spotify Received pause Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: SPOTIFY PAUSE Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: {"status":"play","position":0,"title":"Kiss In Blue","artist":"Yello","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d0000b27366ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","codec":"ogg","seek":0,"duration":213,"samplerate":"160 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":90,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Sep 08 16:13:16 volumio volumio[981]: info: Sending Spotify command to local API: /player/pause Sep 08 16:13:16 volumio go-librespot[5473]: time="2025-09-08T16:13:16+03:00" level=debug msg="pause track at 2005ms" Sep 08 16:13:16 volumio go-librespot[5473]: time="2025-09-08T16:13:16+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 08 16:13:16 volumio go-librespot[5473]: time="2025-09-08T16:13:16+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 08 16:13:16 volumio go-librespot[5473]: time="2025-09-08T16:13:16+03:00" level=trace msg="emitting websocket event: paused" Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","play_origin":"go-librespot"}} Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: {"status":"pause","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":1000,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:16 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:13:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:16 volumio volumio[981]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":1000,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:16 volumio volumio[981]: verbose: CURRENT POSITION 0 Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::syncState stateService pause Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus pause Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:16 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 16:13:16 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","play_origin":"go-librespot"}} Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: PUSH STATE SPOTIFY Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: {"status":"pause","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":1000,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:16 volumio volumio[981]: info: CoreCommandRouter::servicePushState Sep 08 16:13:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:16 volumio volumio[981]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Kiss In Blue","artist":"Yello, Heidi Happy","album":"Touch Yello (Deluxe)","albumart":"https://i.scdn.co/image/ab67616d00001e0266ca95a56e4f4a7ab04ef01f","uri":"spotify:track:4kfAJUz1aLUOSmhq1ljP8s","trackType":"spotify","seek":1000,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 08 16:13:16 volumio volumio[981]: verbose: CURRENT POSITION 0 Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::syncState stateService pause Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::syncState currentStatus pause Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::pushState Sep 08 16:13:16 volumio volumio[981]: info: CorePlayQueue::getTrack 0 Sep 08 16:13:16 volumio volumio[981]: info: CoreCommandRouter::volumioPushState Sep 08 16:13:16 volumio volumio[981]: info: CoreStateMachine::stPlaybackTimer Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:13:16 volumio volumio[981]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Sep 08 16:13:17 volumio go-librespot[5473]: time="2025-09-08T16:13:17+03:00" level=trace msg="sent dealer ping" Sep 08 16:13:17 volumio go-librespot[5473]: time="2025-09-08T16:13:17+03:00" level=trace msg="received dealer pong" Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:21 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 08 16:13:22 volumio volumio[981]: info: Preload queue cleared Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:22 volumio volumio[981]: error: error Sep 08 16:13:24 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 08 16:13:24 volumio volumio[981]: info: Preload queue cleared Sep 08 16:13:25 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 08 16:13:25 volumio volumio[981]: info: Preload queue cleared Sep 08 16:13:27 volumio volumio[981]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 08 16:13:27 volumio volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 16:13:27 volumio volumio[981]: TypeError: Cannot read property 'length' of undefined Sep 08 16:13:27 volumio volumio[981]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 08 16:13:27 volumio volumio[981]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 08 16:13:27 volumio volumio[981]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 08 16:13:27 volumio volumio[981]: at Parser.emit (events.js:315:20) Sep 08 16:13:27 volumio volumio[981]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 08 16:13:27 volumio volumio[981]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 08 16:13:27 volumio volumio[981]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 08 16:13:27 volumio volumio[981]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 08 16:13:27 volumio volumio[981]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 08 16:13:27 volumio volumio[981]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 08 16:13:27 volumio volumio[981]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 08 16:13:27 volumio volumio[981]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 08 16:13:27 volumio volumio[981]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 08 16:13:27 volumio volumio[981]: at IncomingMessage.emit (events.js:327:22) Sep 08 16:13:27 volumio volumio[981]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 08 16:13:27 volumio volumio[981]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 08 16:13:27 volumio volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 16:13:27 volumio sudo[3721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 16:12 Sep 08 16:13:27 volumio sudo[3721]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"