Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: ---------------------------- Client requests Volumio play Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioPlay Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: verbose: UNSET VOLATILE: Service: undefined Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::play index undefined Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::startPlaybackTimer Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/music@c9ae4b42d8fd494ab75863ca22d1c4c9/song@songId=6483a073f2c9b346a09e98bd65fe1753 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: [jellyfin-conn] Using previously assigned device Id for music@jellyfin-neo: 777d86fe-f9a7-4a6b-b5d3-645b1c861cf5 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::resetVolumioState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::getcurrentVolume Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioStop Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::stop Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: VolumeController:: Volume=91 Mute =false Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::pushState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioPushState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output update for this device Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::updateTrackBlock Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrackBlock Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioRetrievevolume Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: VolumeController:: Volume=91 Mute =false Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::pushState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioPushState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output update for this device Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Dec 11 05:50:01 gbvpi-bedroom volumio[19973]: info: [jellyfin-conn] Login successful: music@jellyfin-neo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: [jellyfin-play] Stream URL for Surrounded: https://jellyfin.lukeaurio.net/Audio/6483a073f2c9b346a09e98bd65fe1753/stream.mp3?static=true&mediaSourceId=6483a073f2c9b346a09e98bd65fe1753&tag=fb0574f0128ba6389db309b5a1ac10ee Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand stop took 2 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: ---------------------------- MPD announces system playlist update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Ignoring MPD Status Update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand clear took 3 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand load "https://jellyfin.lukeaurio.net/Audio/6483a073f2c9b346a09e98bd65fe1753/stream.mp3?static=true&mediaSourceId=6483a073f2c9b346a09e98bd65fe1753&tag=fb0574f0128ba6389db309b5a1ac10ee&t.mp3" Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: error: updateQueue error: null Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ------------------------------ 5ms Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand addid "https://jellyfin.lukeaurio.net/Audio/6483a073f2c9b346a09e98bd65fe1753/stream.mp3?static=true&mediaSourceId=6483a073f2c9b346a09e98bd65fe1753&tag=fb0574f0128ba6389db309b5a1ac10ee&t.mp3" Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: ---------------------------- MPD announces system playlist update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Ignoring MPD Status Update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand addid "https://jellyfin.lukeaurio.net/Audio/6483a073f2c9b346a09e98bd65fe1753/stream.mp3?static=true&mediaSourceId=6483a073f2c9b346a09e98bd65fe1753&tag=fb0574f0128ba6389db309b5a1ac10ee&t.mp3" took 2 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: MPD COMMAND [object Object] Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: MPD COMMAND [object Object] Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: MPD COMMAND [object Object] Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: ---------------------------- MPD announces system playlist update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Ignoring MPD Status Update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ------------------------------ 4ms Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand play Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ------------------------------ 2ms Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand play took 2 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: [jellyfin-play]: Mark song Surrounded as played by music. Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::getState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand status Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: ---------------------------- MPD announces state update: player Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::getState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand status Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::getState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand status Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: ---------------------------- MPD announces state update: player Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::getState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand status Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand status took 6 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand status took 6 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: ---------------------------- MPD announces system playlist update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Ignoring MPD Status Update Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::getState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand status Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: ---------------------------- MPD announces state update: player Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand status took 11 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::getState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand status Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand status took 14 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand status took 6 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ------------------------------ 9ms Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand status took 5 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseTrackInfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseTrackInfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::pushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::servicePushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":141,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Surrounded","artist":"The Mountain Goats & John Vanderslice","album":"Moon Colony Bloodbath","uri":"https://jellyfin.lukeaurio.net/Audio/6483a073f2c9b346a09e98bd65fe1753/stream.mp3?static=true&mediaSourceId=6483a073f2c9b346a09e98bd65fe1753&tag=fb0574f0128ba6389db309b5a1ac10ee&t.mp3","trackType":"mp3"} Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: CURRENT POSITION 0 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::syncState stateService play Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::syncState currentStatus stop Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ------------------------------ 32ms Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Pushing Favourites {"favourite":false} Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseTrackInfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseTrackInfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseTrackInfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: ControllerMpd::parseTrackInfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::pushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::servicePushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":141,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Surrounded","artist":"The Mountain Goats & John Vanderslice","album":"Moon Colony Bloodbath","uri":"https://jellyfin.lukeaurio.net/Audio/6483a073f2c9b346a09e98bd65fe1753/stream.mp3?static=true&mediaSourceId=6483a073f2c9b346a09e98bd65fe1753&tag=fb0574f0128ba6389db309b5a1ac10ee&t.mp3","trackType":"mp3"} Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: CURRENT POSITION 0 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::syncState stateService play Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::syncState currentStatus play Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Received an update from plugin. extracting info from payload Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::pushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioPushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output update for this device Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::pushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioPushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output update for this device Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ControllerMpd::pushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::servicePushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CorePlayQueue::getTrack 0 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":141,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Surrounded","artist":"The Mountain Goats & John Vanderslice","album":"Moon Colony Bloodbath","uri":"https://jellyfin.lukeaurio.net/Audio/6483a073f2c9b346a09e98bd65fe1753/stream.mp3?static=true&mediaSourceId=6483a073f2c9b346a09e98bd65fe1753&tag=fb0574f0128ba6389db309b5a1ac10ee&t.mp3","trackType":"mp3"} Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: verbose: CURRENT POSITION 0 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::syncState stateService play Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::syncState currentStatus play Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Received an update from plugin. extracting info from payload Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::pushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioPushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output update for this device Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreStateMachine::pushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioPushState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output update for this device Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: MRS: Pushing multiroomSync output Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ------------------------------ 139ms Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: ------------------------------ 129ms Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Pushing Favourites {"favourite":false} Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: Pushing Favourites {"favourite":false} Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Dec 11 05:50:02 gbvpi-bedroom volumio[19973]: info: [jellyfin-play]: Reported 'start' for song: Surrounded (at 0 ms) Dec 11 05:50:03 gbvpi-bedroom volumio[19973]: info: [jellyfin-play]: Reported 'timeupdate' for song: Surrounded (at 1236 ms) Dec 11 05:50:03 gbvpi-bedroom volumio[19973]: info: [jellyfin-play]: Reported 'timeupdate' for song: Surrounded (at 0 ms) Dec 11 05:50:04 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:04 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:14 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:14 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:24 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:24 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:34 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:34 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:44 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:44 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:54 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:54 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:50:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:50:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:04 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:04 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:14 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:14 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:14 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:24 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:24 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:34 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:34 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:34 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:44 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:44 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:54 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:54 gbvpi-bedroom volumio[19973]: info: CoreCommandRouter::volumioGetState Dec 11 05:51:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:54 gbvpi-bedroom volumio[19973]: info: Listing playlists Dec 11 05:51:55 gbvpi-bedroom go-librespot[20473]: time="2025-12-11T05:51:55-05:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 11 05:51:55 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Dec 11 05:51:55 gbvpi-bedroom systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: Error: socket hang up Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: at connResetException (node:internal/errors:720:14) Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: at Socket.emit (node:events:526:35) Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: code: 'ECONNRESET', Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: response: undefined Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: } Dec 11 05:51:55 gbvpi-bedroom volumio[19973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 05:51:55 gbvpi-bedroom sudo[21562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 05:50' Dec 11 05:51:55 gbvpi-bedroom sudo[21562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="6a0bffa825dc2af90b21170949f5219ba5ab5701" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Dec 10 09:27:23 UTC 2025" VOLUMIO_VERSION="4.075" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bd01f93a654c7068f4553fbd69478e2"