-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-11-01 19:12:39 CET. -- Nov 01 19:11:08 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 19:11:08 volumio-wohnzimmer volumio[946]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 99 Nov 01 19:11:08 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioGetState Nov 01 19:11:08 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPlay Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index 0 Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stop Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index undefined Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::startPlaybackTimer Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: [1762020672860] ControllerSpotify::clearAddPlayTrack Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: info: Sending Spotify command with payload to local API: /player/play Nov 01 19:11:12 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:11:12+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 01 19:11:12 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:11:12+01:00" level=debug msg="loading track spotify:track:5OJbTix8eJQuZhIUJK8EDW (paused: false, position: 0ms)" Nov 01 19:11:12 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:11:12+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 01 19:11:12 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:11:12+01:00" level=trace msg="emitting websocket event: will_play" Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5OJbTix8eJQuZhIUJK8EDW","play_origin":"go-librespot"}} Nov 01 19:11:12 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:11:12+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:5OJbTix8eJQuZhIUJK8EDW" Nov 01 19:11:12 volumio-wohnzimmer volumio[946]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 19:11:13 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: info: Completed starting MyVolumio Plugin Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: [Metrics] CommandRouter: 105s 808.60ms Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumiosetStartupVolume Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::Close All Modals sent Nov 01 19:11:14 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::Close All Modals sent Nov 01 19:11:15 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 01 19:11:15 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 19:11:15 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 01 19:11:16 volumio-wohnzimmer volumio[1267]: .......................................................................................................................................................................................................................................++++ Nov 01 19:11:17 volumio-wohnzimmer volumio[1267]: .........++++ Nov 01 19:11:17 volumio-wohnzimmer volumio[1267]: e is 65537 (0x010001) Nov 01 19:11:17 volumio-wohnzimmer volumio[1267]: writing RSA key Nov 01 19:11:21 volumio-wohnzimmer volumio[946]: info: BOOT COMPLETED Nov 01 19:11:27 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 01 19:11:27 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:28 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 01 19:11:28 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::ClearQueue Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stop Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::clearPlayQueue Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::saveQueue Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushQueue Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::addQueueItems Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::addQueueItems Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s286815 Nov 01 19:11:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushQueue Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::saveQueue Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::updateTrackBlock Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrackBlock Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPlay Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index 0 Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stop Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index undefined Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::startPlaybackTimer Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: [1762020691795] ControllerWebradio::clearAddPlayTrack Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: sendMpdCommand stop took 7 milliseconds Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand clear Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: sendMpdCommand clear took 1 milliseconds Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand load "http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3" Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:31 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand add "http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3" Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: error: updateQueue error: null Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: error: updateQueue error: null Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: error: updateQueue error: null Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: ------------------------------ 2664ms Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: ------------------------------ 2661ms Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: ------------------------------ 2659ms Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: sendMpdCommand add "http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3" took 11 milliseconds Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand play Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: Upnp client error: Error: This socket has been ended by the other party Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: ------------------------------ 14ms Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: sendMpdCommand play took 10 milliseconds Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: ------------------------------ 8ms Nov 01 19:11:34 volumio-wohnzimmer volumio[946]: info: ------------------------------ 6ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 17 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 20 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 18 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 5 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":null,"album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus stop Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 41ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 19 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 10 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 8 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":null,"album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":null,"album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":null,"album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 153ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 152ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 141ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 108 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 106 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 187ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 186ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 104ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 101 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 103ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 101 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 37ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 36 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 35ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 32 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 14 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 13ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 11 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 11ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 8 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 256ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 263ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 196ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 195ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 144 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 144 milliseconds Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"stream.mp3","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 228ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: info: ------------------------------ 226ms Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:35 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:38 volumio-wohnzimmer volumiologrotate[577]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 01 19:11:38 volumio-wohnzimmer volumiologrotate[577]: ls: cannot access 'WOHNZIMMER': No such file or directory Nov 01 19:11:39 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 01 19:11:39 volumio-wohnzimmer volumio[946]: info: In handleBrowseUri, curUri=spotify Nov 01 19:11:39 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:39 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:39 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:39 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces system playlist update Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Ignoring MPD Status Update Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ------------------------------ 17ms Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 14 milliseconds Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ------------------------------ 13ms Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 11 milliseconds Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ------------------------------ 11ms Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 8 milliseconds Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"MICKIE KRAUSE - SCHATZI SCHENK MIR EIN FOTO","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"MICKIE KRAUSE - SCHATZI SCHENK MIR EIN FOTO","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":24685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"MICKIE KRAUSE - SCHATZI SCHENK MIR EIN FOTO","artist":"Radio Bollerwagen","album":null,"uri":"http://stream.ffn.de/radiobollerwagen/mp3-192/stream.mp3","trackType":"mp3"} Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: verbose: CURRENT POSITION 0 Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState stateService play Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::syncState currentStatus play Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: Received an update from plugin. extracting info from payload Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ------------------------------ 112ms Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ------------------------------ 109ms Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: info: ------------------------------ 107ms Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:11:59 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: In handleBrowseUri, curUri=spotify:album:0z7l9VEJyFMv8p8wffRDaF Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:7tMF8Anyf7oIqQZSxE17do Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:3qrht50EwYuMgjPkM2A3aJ Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:2yuKbNMCQ8Oo6KWWZvUCoV Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:6ExwNwWLXvvKr5hk6lkHUY Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:2R0HZcJCaamgHlY5gdNIi7 Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:0JAaTG7sEjukCjC8X58puq Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:4BswiLDcr4ChDGl2NrfhC1 Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:6Z2hokFLCbEXKnnBosDj8u Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:21sDgrgghtQMdUGsCqGVXI Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:1oHxIq2EEXHzPcNo3MJQjQ Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:3ZIXPNe0ZsLdUXlIHGNAdx Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Preloading song: spotify:track:7qqmsn4mjWvsXjBALuw8Jr Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:7tMF8Anyf7oIqQZSxE17do in service spop Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:7tMF8Anyf7oIqQZSxE17do Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:3qrht50EwYuMgjPkM2A3aJ in service spop Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:3qrht50EwYuMgjPkM2A3aJ Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:2yuKbNMCQ8Oo6KWWZvUCoV in service spop Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:2yuKbNMCQ8Oo6KWWZvUCoV Nov 01 19:12:01 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7tMF8Anyf7oIqQZSxE17do","service":"spop","name":"Everybody Scream","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":244,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:6ExwNwWLXvvKr5hk6lkHUY in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:6ExwNwWLXvvKr5hk6lkHUY Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3qrht50EwYuMgjPkM2A3aJ","service":"spop","name":"One of the Greats","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":392,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:2R0HZcJCaamgHlY5gdNIi7 in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:2R0HZcJCaamgHlY5gdNIi7 Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: No valid Plugin REST Endpoint Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:0JAaTG7sEjukCjC8X58puq in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:0JAaTG7sEjukCjC8X58puq Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:4BswiLDcr4ChDGl2NrfhC1 in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:4BswiLDcr4ChDGl2NrfhC1 Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yuKbNMCQ8Oo6KWWZvUCoV","service":"spop","name":"Witch Dance","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":263,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:6Z2hokFLCbEXKnnBosDj8u in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:6Z2hokFLCbEXKnnBosDj8u Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ExwNwWLXvvKr5hk6lkHUY","service":"spop","name":"Sympathy Magic","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":268,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:21sDgrgghtQMdUGsCqGVXI in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:21sDgrgghtQMdUGsCqGVXI Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0JAaTG7sEjukCjC8X58puq","service":"spop","name":"Buckle","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:1oHxIq2EEXHzPcNo3MJQjQ in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:1oHxIq2EEXHzPcNo3MJQjQ Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6Z2hokFLCbEXKnnBosDj8u","service":"spop","name":"The Old Religion","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4BswiLDcr4ChDGl2NrfhC1","service":"spop","name":"Kraken","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:3ZIXPNe0ZsLdUXlIHGNAdx in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:3ZIXPNe0ZsLdUXlIHGNAdx Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:21sDgrgghtQMdUGsCqGVXI","service":"spop","name":"Drink Deep","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2R0HZcJCaamgHlY5gdNIi7","service":"spop","name":"Perfume and Milk","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":248,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:track:7qqmsn4mjWvsXjBALuw8Jr in service spop Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:track:7qqmsn4mjWvsXjBALuw8Jr Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1oHxIq2EEXHzPcNo3MJQjQ","service":"spop","name":"Music by Men","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":271,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ZIXPNe0ZsLdUXlIHGNAdx","service":"spop","name":"You Can Have It All","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:02 volumio-wohnzimmer volumio[946]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7qqmsn4mjWvsXjBALuw8Jr","service":"spop","name":"And Love","artist":"Florence + The Machine","album":"Everybody Scream","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b27306272cebe18d71e7e4629a38","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::ClearQueue Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stop Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stPlaybackTimer Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::updateTrackBlock Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrackBlock Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::serviceStop Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::serviceStop Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: [1762020726976] ControllerWebradio::stop Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand stop Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::clearPlayQueue Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::saveQueue Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushQueue Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::addQueueItems Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::addQueueItems Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:7tMF8Anyf7oIqQZSxE17do Nov 01 19:12:06 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:7tMF8Anyf7oIqQZSxE17do Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushQueue Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::saveQueue Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::updateTrackBlock Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrackBlock Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPlay Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::addQueueItems Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::addQueueItems Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:3qrht50EwYuMgjPkM2A3aJ Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:3qrht50EwYuMgjPkM2A3aJ Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:2yuKbNMCQ8Oo6KWWZvUCoV Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:2yuKbNMCQ8Oo6KWWZvUCoV Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:6ExwNwWLXvvKr5hk6lkHUY Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:6ExwNwWLXvvKr5hk6lkHUY Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:2R0HZcJCaamgHlY5gdNIi7 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:2R0HZcJCaamgHlY5gdNIi7 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:0JAaTG7sEjukCjC8X58puq Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:0JAaTG7sEjukCjC8X58puq Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:4BswiLDcr4ChDGl2NrfhC1 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:4BswiLDcr4ChDGl2NrfhC1 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:6Z2hokFLCbEXKnnBosDj8u Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:6Z2hokFLCbEXKnnBosDj8u Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:21sDgrgghtQMdUGsCqGVXI Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:21sDgrgghtQMdUGsCqGVXI Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:1oHxIq2EEXHzPcNo3MJQjQ Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:1oHxIq2EEXHzPcNo3MJQjQ Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:3ZIXPNe0ZsLdUXlIHGNAdx Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:3ZIXPNe0ZsLdUXlIHGNAdx Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:track:7qqmsn4mjWvsXjBALuw8Jr Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Using cached record of: spotify:track:7qqmsn4mjWvsXjBALuw8Jr Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stop Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushQueue Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::saveQueue Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index undefined Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::updateTrackBlock Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrackBlock Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::startPlaybackTimer Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: [1762020727033] ControllerSpotify::clearAddPlayTrack Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Sending Spotify command with payload to local API: /player/play Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: sendMpdCommand stop took 71 milliseconds Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: ---------------------------- MPD announces state update: player Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ControllerMpd::getState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand status Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 7 milliseconds Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 6 milliseconds Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: sendMpdCommand status took 4 milliseconds Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: verbose: ControllerMpd::parseTrackInfo Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Nov 01 19:12:07 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:07+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 01 19:12:07 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:07+01:00" level=debug msg="loading track spotify:track:7tMF8Anyf7oIqQZSxE17do (paused: false, position: 0ms)" Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ControllerMpd::pushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::servicePushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::pushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushState Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Nov 01 19:12:07 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 01 19:12:07 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:07+01:00" level=trace msg="emitting websocket event: will_play" Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ------------------------------ 82ms Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ------------------------------ 80ms Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: info: ------------------------------ 79ms Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7tMF8Anyf7oIqQZSxE17do","play_origin":"go-librespot"}} Nov 01 19:12:07 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:07+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:7tMF8Anyf7oIqQZSxE17do" Nov 01 19:12:07 volumio-wohnzimmer volumio[946]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 01 19:12:07 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:07+01:00" level=debug msg="skipping dealer message" uri=playback-settings/content-settings-update Nov 01 19:12:07 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 159" Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::ClearQueue Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stop Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::clearPlayQueue Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::saveQueue Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushQueue Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::addQueueItems Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::addQueueItems Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: Adding Item to queue: spotify:album:0z7l9VEJyFMv8p8wffRDaF Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: Exploding uri spotify:album:0z7l9VEJyFMv8p8wffRDaF in service spop Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: SPOTIFY: EXPLODING URI:spotify:album:0z7l9VEJyFMv8p8wffRDaF Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPushQueue Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::saveQueue Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::updateTrackBlock Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrackBlock Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::volumioPlay Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index 0 Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::stop Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::play index undefined Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CoreStateMachine::startPlaybackTimer Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: CorePlayQueue::getTrack 0 Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: [1762020739813] ControllerSpotify::clearAddPlayTrack Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: info: Sending Spotify command with payload to local API: /player/play Nov 01 19:12:19 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:19+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Nov 01 19:12:19 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:19+01:00" level=debug msg="loading track spotify:track:7tMF8Anyf7oIqQZSxE17do (paused: false, position: 0ms)" Nov 01 19:12:19 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:19+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 01 19:12:19 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:19+01:00" level=trace msg="emitting websocket event: will_play" Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7tMF8Anyf7oIqQZSxE17do","play_origin":"go-librespot"}} Nov 01 19:12:19 volumio-wohnzimmer go-librespot[1309]: time="2025-11-01T19:12:19+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:7tMF8Anyf7oIqQZSxE17do" Nov 01 19:12:19 volumio-wohnzimmer volumio[946]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 01 19:12:24 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 01 19:12:24 volumio-wohnzimmer volumio[946]: info: In handleBrowseUri, curUri=spotify Nov 01 19:12:25 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:25 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:25 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:25 volumio-wohnzimmer volumio[946]: info: Preload queue cleared Nov 01 19:12:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 19:12:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 01 19:12:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 01 19:12:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 01 19:12:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Nov 01 19:12:30 volumio-wohnzimmer volumio[946]: info: Received Get System Version Nov 01 19:12:30 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 19:12:37 volumio-wohnzimmer volumio-remote-updater[572]: No test mode Nov 01 19:12:37 volumio-wohnzimmer volumio-remote-updater[572]: No alpha test mode Nov 01 19:12:37 volumio-wohnzimmer volumio[946]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v3.832","updateavailable":true} Nov 01 19:12:37 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 01 19:12:38 volumio-wohnzimmer volumiologrotate[577]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 01 19:12:38 volumio-wohnzimmer volumiologrotate[577]: ls: cannot access 'WOHNZIMMER': No such file or directory Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: info: Update: [object Object] Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: info: CoreCommandRouter::executeOnPlugin: updater_comm , checkSystemIntegrity Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: TypeError: Cannot read property 'then' of undefined Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:987:22) Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: at Socket.emit (events.js:315:20) Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 01 19:12:38 volumio-wohnzimmer volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 19:12:39 volumio-wohnzimmer sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 19:11 Nov 01 19:12:39 volumio-wohnzimmer sudo[1781]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"