-- 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- Fix plugin installations due to apt repository
\n- Fix chromecast playback
\n
\nNEW ADDITIONS
\n\n- Add link for downloading CORRD in the side-menu
\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"