-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2025-07-02 18:19:06 CEST. -- Jul 02 18:18:07 volumio5bern go-librespot[1506]: time="2025-07-02T18:18:07+02:00" level=trace msg="sent dealer ping" Jul 02 18:18:07 volumio5bern go-librespot[1506]: time="2025-07-02T18:18:07+02:00" level=trace msg="received dealer pong" Jul 02 18:18:16 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 18:18:16 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 18:18:16 volumio5bern volumio[1223]: info: Discovery: Getting this device information Jul 02 18:18:16 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:16 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:16 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioSeek Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::seek Jul 02 18:18:32 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:32 volumio5bern volumio[1223]: info: TRACKBLOCK {"album":"BLINK TWICE","albumUri":"tidal://album/396003870","albumart":"https://resources.tidal.com/images/6ee37017/c029/4182/a501/22e1f97bd0af/640x640.jpg","artist":"James Gruntz","artistUri":"tidal://artist/4504561","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":150,"explicit":false,"name":"RIGHT AWAY","samplerate":"44.1 KHz","service":"tidal","title":"RIGHT AWAY","trackType":"tidal","tracknumber":11,"type":"track","uri":"tidal://song/396003884","volumeNumber":1,"channels":2} Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::startPlaybackTimer Jul 02 18:18:32 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:32 volumio5bern volumio[1223]: info: [1751473112557] ControllerTidal::seek Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:18:32 volumio5bern volumio[1223]: info: ControllerMpd::seek Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:32 volumio5bern volumio[1223]: error: null Jul 02 18:18:32 volumio5bern volumio[1223]: info: Jul 02 18:18:32 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:32 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:32 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:32 volumio5bern volumio[1223]: info: Jul 02 18:18:32 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:32 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:32 volumio5bern volumio[1223]: info: Jul 02 18:18:32 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:32 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:32 volumio5bern volumio[1223]: info: sendMpdCommand status took 6 milliseconds Jul 02 18:18:32 volumio5bern volumio[1223]: info: sendMpdCommand status took 4 milliseconds Jul 02 18:18:32 volumio5bern volumio[1223]: info: sendMpdCommand status took 4 milliseconds Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:32 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:32 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:32 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:32 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104983,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"783 Kbps","isStreaming":false,"title":"396003884&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003884&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: CURRENT POSITION 10 Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:32 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104983,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"783 Kbps","isStreaming":false,"title":"396003884&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003884&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: CURRENT POSITION 10 Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:32 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":104983,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"783 Kbps","isStreaming":false,"title":"396003884&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003884&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:32 volumio5bern volumio[1223]: verbose: CURRENT POSITION 10 Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:32 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:32 volumio5bern volumio[1223]: info: ------------------------------ 30ms Jul 02 18:18:32 volumio5bern volumio[1223]: info: ------------------------------ 27ms Jul 02 18:18:32 volumio5bern volumio[1223]: info: ------------------------------ 28ms Jul 02 18:18:32 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:32 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:32 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:32 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:32 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioNext Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::next Jul 02 18:18:33 volumio5bern volumio[1223]: info: [1751473113397] ControllerTidal::next Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::next Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::stop Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::stPlaybackTimer Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::updateTrackBlock Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrackBlock Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::serviceStop Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 10 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::serviceStop Jul 02 18:18:33 volumio5bern volumio[1223]: info: [1751473113401] ControllerTidal::stop Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::stop Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 18:18:33 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:33 volumio5bern volumio[1223]: info: [GPIO_Control] Handling event: musicStop Jul 02 18:18:33 volumio5bern volumio[1223]: info: [GPIO_Control] *** musicStop *** Jul 02 18:18:33 volumio5bern volumio[1223]: info: [GPIO_Control] Turning GPIO 4 Off (musicStop) Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand stop took 7 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::play index undefined Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::startPlaybackTimer Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: [1751473113409] ControllerTidal::clearAddPlayTrack Jul 02 18:18:33 volumio5bern volumio[1223]: info: Getting stream with soundQuality HI_RES Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::updateTrackBlock Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrackBlock Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 2 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand stop took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand clear Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand clear took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES" Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jul 02 18:18:33 volumio5bern volumio[1223]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 02 18:18:33 volumio5bern volumio[1223]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 9ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 8ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 8ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:33 volumio5bern volumio[1223]: error: updateQueue error: null Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 7ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES" took 7 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 1ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 1ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand play Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:33 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:33 volumio5bern volumio[1223]: STREAMING PROXY: Handling url /?data=tidal://song/396003877&soundQuality=HI_RES Jul 02 18:18:33 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 4ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand play took 4 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 4ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 3ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: Executing endpoint getStreamUrltidal Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 02 18:18:33 volumio5bern volumio[1223]: info: getStreamUrl took 241 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRmYTNlOTIxYTM5Y2IxNzI4OTQ1NzU0MmMyYzAzYjZhNC5tcDQ/0.flac?token=1751476713~YTliOTNlY2EwYWExMTZkZGFkOWFmMzYwZGYyNTI0MWMxNWQwYzE1YQ== Jul 02 18:18:33 volumio5bern volumio[1223]: STREAMING PROXY: Response: 200, length: 11100516 Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 2 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 2 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003877&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: CURRENT POSITION 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003877&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: CURRENT POSITION 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:33 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 8ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: ------------------------------ 13ms Jul 02 18:18:33 volumio5bern volumio[1223]: info: Jul 02 18:18:33 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003877&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: CURRENT POSITION 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:33 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003877&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: CURRENT POSITION 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:33 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003877&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:33 volumio5bern volumio[1223]: verbose: CURRENT POSITION 4 Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:33 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:33 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:34 volumio5bern volumio[1223]: info: ------------------------------ 49ms Jul 02 18:18:34 volumio5bern volumio[1223]: info: ------------------------------ 49ms Jul 02 18:18:34 volumio5bern volumio[1223]: info: ------------------------------ 48ms Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Handling event: musicPlay Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] *** musicPlay *** Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Turning GPIO 4 On (musicPlay) Jul 02 18:18:34 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 87 milliseconds Jul 02 18:18:34 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:34 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:34 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:34 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":124,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003877&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003877&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:34 volumio5bern volumio[1223]: verbose: CURRENT POSITION 4 Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:34 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:34 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:34 volumio5bern volumio[1223]: info: ------------------------------ 97ms Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:34 volumio5bern kernel: hwmon hwmon2: Undervoltage detected! Jul 02 18:18:35 volumio5bern volumio[1223]: STREAMING PROXY: Client dropped request, destroying Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioNext Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::next Jul 02 18:18:35 volumio5bern volumio[1223]: info: [1751473115818] ControllerTidal::next Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::next Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::stop Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::stPlaybackTimer Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::updateTrackBlock Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrackBlock Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::serviceStop Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 4 Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::serviceStop Jul 02 18:18:35 volumio5bern volumio[1223]: info: [1751473115822] ControllerTidal::stop Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:18:35 volumio5bern volumio[1223]: info: ControllerMpd::stop Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 18:18:35 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:35 volumio5bern volumio[1223]: info: [GPIO_Control] Handling event: musicStop Jul 02 18:18:35 volumio5bern volumio[1223]: info: [GPIO_Control] *** musicStop *** Jul 02 18:18:35 volumio5bern volumio[1223]: info: [GPIO_Control] Turning GPIO 4 Off (musicStop) Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand stop took 6 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::play index undefined Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::startPlaybackTimer Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:35 volumio5bern volumio[1223]: info: [1751473115828] ControllerTidal::clearAddPlayTrack Jul 02 18:18:35 volumio5bern volumio[1223]: info: Getting stream with soundQuality HI_RES Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::updateTrackBlock Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrackBlock Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:35 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand stop took 1 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand clear Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:35 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:35 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand clear took 1 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES" Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:35 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:35 volumio5bern volumio[1223]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jul 02 18:18:35 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:35 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:35 volumio5bern volumio[1223]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 11ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 10ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:35 volumio5bern volumio[1223]: error: updateQueue error: null Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 12ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES" took 11 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 2ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 2ms Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:18:35 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand play Jul 02 18:18:35 volumio5bern volumio[1223]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 14ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Jul 02 18:18:35 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:35 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:35 volumio5bern volumio[1223]: STREAMING PROXY: Handling url /?data=tidal://song/396003874&soundQuality=HI_RES Jul 02 18:18:35 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:35 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 6ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: sendMpdCommand play took 5 milliseconds Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 5ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: ------------------------------ 5ms Jul 02 18:18:35 volumio5bern volumio[1223]: info: Executing endpoint getStreamUrltidal Jul 02 18:18:35 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 02 18:18:36 volumio5bern volumio[1223]: info: getStreamUrl took 179 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1ZTUzYjc0ZWI5ZWM1NjIzMzZkMTIyOWQyNWRhYjFmMC5tcDQ/0.flac?token=1751476715~ZGJjNDBhNDNlNDE2NDA4OWNjZmYwZTMxOTdlNjlmZmQ4ZTc2MDQ1OA== Jul 02 18:18:36 volumio5bern volumio[1223]: STREAMING PROXY: Response: 200, length: 20770374 Jul 02 18:18:36 volumio5bern volumio[1223]: info: Jul 02 18:18:36 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:36 volumio5bern volumio[1223]: info: Jul 02 18:18:36 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:36 volumio5bern volumio[1223]: info: Jul 02 18:18:36 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:36 volumio5bern volumio[1223]: info: Jul 02 18:18:36 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:36 volumio5bern volumio[1223]: info: Jul 02 18:18:36 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:36 volumio5bern volumio[1223]: info: Jul 02 18:18:36 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand status took 2 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003874&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: CURRENT POSITION 1 Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003874&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: CURRENT POSITION 1 Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:36 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: ------------------------------ 9ms Jul 02 18:18:36 volumio5bern volumio[1223]: info: ------------------------------ 13ms Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand status took 12 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003874&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: CURRENT POSITION 1 Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:36 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003874&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: CURRENT POSITION 1 Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:36 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003874&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: CURRENT POSITION 1 Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:36 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: ------------------------------ 47ms Jul 02 18:18:36 volumio5bern volumio[1223]: info: ------------------------------ 47ms Jul 02 18:18:36 volumio5bern volumio[1223]: info: ------------------------------ 46ms Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Handling event: musicPlay Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] *** musicPlay *** Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Turning GPIO 4 On (musicPlay) Jul 02 18:18:36 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 50 milliseconds Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:36 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003874&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003874&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:36 volumio5bern volumio[1223]: verbose: CURRENT POSITION 1 Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:36 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:36 volumio5bern volumio[1223]: info: ------------------------------ 77ms Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:36 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:37 volumio5bern go-librespot[1506]: time="2025-07-02T18:18:37+02:00" level=trace msg="sent dealer ping" Jul 02 18:18:37 volumio5bern go-librespot[1506]: time="2025-07-02T18:18:37+02:00" level=trace msg="received dealer pong" Jul 02 18:18:38 volumio5bern kernel: hwmon hwmon2: Voltage normalised Jul 02 18:18:38 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetQueue Jul 02 18:18:38 volumio5bern volumio[1223]: info: CoreStateMachine::getQueue Jul 02 18:18:38 volumio5bern volumio[1223]: info: CorePlayQueue::getQueue Jul 02 18:18:39 volumio5bern volumio[1223]: STREAMING PROXY: Client dropped request, destroying Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioNext Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::next Jul 02 18:18:40 volumio5bern volumio[1223]: info: [1751473120412] ControllerTidal::next Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::next Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::stop Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::stPlaybackTimer Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::updateTrackBlock Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrackBlock Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::serviceStop Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 1 Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::serviceStop Jul 02 18:18:40 volumio5bern volumio[1223]: info: [1751473120416] ControllerTidal::stop Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:18:40 volumio5bern volumio[1223]: info: ControllerMpd::stop Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 18:18:40 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:40 volumio5bern volumio[1223]: info: [GPIO_Control] Handling event: musicStop Jul 02 18:18:40 volumio5bern volumio[1223]: info: [GPIO_Control] *** musicStop *** Jul 02 18:18:40 volumio5bern volumio[1223]: info: [GPIO_Control] Turning GPIO 4 Off (musicStop) Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand stop took 7 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::play index undefined Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::startPlaybackTimer Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:40 volumio5bern volumio[1223]: info: [1751473120424] ControllerTidal::clearAddPlayTrack Jul 02 18:18:40 volumio5bern volumio[1223]: info: Getting stream with soundQuality HI_RES Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::updateTrackBlock Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrackBlock Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:40 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand stop took 0 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand clear Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:40 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:40 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:40 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand clear took 1 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES" Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:40 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:40 volumio5bern volumio[1223]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jul 02 18:18:40 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:40 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:40 volumio5bern volumio[1223]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 10ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 10ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:40 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:40 volumio5bern volumio[1223]: error: updateQueue error: null Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:40 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 11ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES" took 10 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 1ms Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:18:40 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand play Jul 02 18:18:40 volumio5bern volumio[1223]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 12ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:40 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:40 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:40 volumio5bern volumio[1223]: STREAMING PROXY: Handling url /?data=tidal://song/396003882&soundQuality=HI_RES Jul 02 18:18:40 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:40 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 5ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: sendMpdCommand play took 5 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 5ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 4ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: Jul 02 18:18:40 volumio5bern volumio[1223]: ---------------------------- MPD announces system playlist update Jul 02 18:18:40 volumio5bern volumio[1223]: info: Ignoring MPD Status Update Jul 02 18:18:40 volumio5bern volumio[1223]: info: ------------------------------ 3ms Jul 02 18:18:40 volumio5bern volumio[1223]: info: Executing endpoint getStreamUrltidal Jul 02 18:18:40 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 02 18:18:40 volumio5bern volumio[1223]: info: getStreamUrl took 198 milliseconds Jul 02 18:18:40 volumio5bern volumio[1223]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRjYTcyYTg1OThjZDkxMWQwOWFiNWVkZWUzYTZmZjUzMS5tcDQ/0.flac?token=1751476720~YjViZTFmODExMWM1ZTFjOWM4OGZiYjgxNDI0ZTc0NGY1ZjU0ZjgwYg== Jul 02 18:18:41 volumio5bern volumio[1223]: STREAMING PROXY: Response: 200, length: 22837117 Jul 02 18:18:41 volumio5bern volumio[1223]: info: Jul 02 18:18:41 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:41 volumio5bern volumio[1223]: info: Jul 02 18:18:41 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:41 volumio5bern volumio[1223]: info: Jul 02 18:18:41 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:41 volumio5bern volumio[1223]: info: Jul 02 18:18:41 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:41 volumio5bern volumio[1223]: info: Jul 02 18:18:41 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand status took 2 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:41 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: ------------------------------ 8ms Jul 02 18:18:41 volumio5bern volumio[1223]: info: ------------------------------ 12ms Jul 02 18:18:41 volumio5bern volumio[1223]: info: Jul 02 18:18:41 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand status took 12 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:41 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:41 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: ------------------------------ 32ms Jul 02 18:18:41 volumio5bern volumio[1223]: info: ------------------------------ 32ms Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Handling event: musicPlay Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] *** musicPlay *** Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Turning GPIO 4 On (musicPlay) Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:41 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:18:41 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService play Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus play Jul 02 18:18:41 volumio5bern volumio[1223]: info: Received an update from plugin. extracting info from payload Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:18:41 volumio5bern volumio[1223]: info: ------------------------------ 57ms Jul 02 18:18:41 volumio5bern volumio[1223]: info: ------------------------------ 46ms Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:41 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: play Jul 02 18:18:42 volumio5bern kernel: hwmon hwmon2: Undervoltage detected! Jul 02 18:18:44 volumio5bern kernel: hwmon hwmon2: Voltage normalised Jul 02 18:18:45 volumio5bern volumio[1223]: STREAMING PROXY: Client dropped request, destroying Jul 02 18:18:46 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jul 02 18:18:46 volumio5bern volumio[1223]: info: browseTIDALUri took 60 milliseconds Jul 02 18:18:46 volumio5bern volumio[1223]: info: Preload queue cleared Jul 02 18:18:46 volumio5bern volumio[1223]: info: Preload queue cleared Jul 02 18:18:49 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jul 02 18:18:49 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 18:18:49 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 18:18:49 volumio5bern volumio[1223]: info: Discovery: Getting this device information Jul 02 18:18:49 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:49 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 18:18:49 volumio5bern volumio[1223]: info: browseTIDALUri took 224 milliseconds Jul 02 18:18:49 volumio5bern volumio[1223]: info: Preload queue cleared Jul 02 18:18:52 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:53 volumio5bern sudo[7092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 02 18:18:53 volumio5bern sudo[7092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 18:18:53 volumio5bern sudo[7092]: pam_unix(sudo:session): session closed for user root Jul 02 18:18:53 volumio5bern sudo[7094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 02 18:18:53 volumio5bern sudo[7094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 18:18:53 volumio5bern sudo[7094]: pam_unix(sudo:session): session closed for user root Jul 02 18:18:53 volumio5bern volumio[1223]: verbose: New Socket.io Connection to 172.22.16.151 from 172.22.16.188 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Pro XL Build/BP2A.250605.031.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 02 18:18:53 volumio5bern sudo[7100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 02 18:18:53 volumio5bern sudo[7100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 18:18:53 volumio5bern sudo[7100]: pam_unix(sudo:session): session closed for user root Jul 02 18:18:53 volumio5bern sudo[7103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 02 18:18:53 volumio5bern sudo[7103]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 18:18:53 volumio5bern sudo[7103]: pam_unix(sudo:session): session closed for user root Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 02 18:18:53 volumio5bern volumio[1223]: verbose: New Socket.io Connection to 172.22.16.151 from 172.22.16.188 UA: Mozilla/5.0 (Linux; Android 16; Pixel 9 Pro XL Build/BP2A.250605.031.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetVisibleSources Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetQueue Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreStateMachine::getQueue Jul 02 18:18:53 volumio5bern volumio[1223]: info: CorePlayQueue::getQueue Jul 02 18:18:53 volumio5bern volumio[1223]: info: Listing playlists Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 02 18:18:53 volumio5bern volumio[1223]: info: Received Get System Info Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 18:18:53 volumio5bern volumio[1223]: info: Discovery: Getting this device information Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 18:18:53 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 02 18:18:55 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 02 18:18:55 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 18:18:55 volumio5bern volumio[1223]: info: Received Get System Info Jul 02 18:18:55 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 18:18:55 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 18:18:55 volumio5bern volumio[1223]: info: Discovery: Getting this device information Jul 02 18:18:55 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:55 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 18:18:56 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 18:18:56 volumio5bern volumio[1223]: info: Received Get System Info Jul 02 18:18:56 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 18:18:56 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 18:18:56 volumio5bern volumio[1223]: info: Discovery: Getting this device information Jul 02 18:18:56 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioGetState Jul 02 18:18:56 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 18:19:00 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 02 18:19:00 volumio5bern volumio[1223]: info: CURURI: playlists Jul 02 18:19:00 volumio5bern volumio[1223]: info: Listing playlists Jul 02 18:19:00 volumio5bern volumio[1223]: info: Preload queue cleared Jul 02 18:19:03 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::ClearQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::stPlaybackTimer Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::updateTrackBlock Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::getTrackBlock Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::serviceStop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::serviceStop Jul 02 18:19:06 volumio5bern volumio[1223]: info: [1751473146308] ControllerTidal::stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 02 18:19:06 volumio5bern volumio[1223]: info: ControllerMpd::stop Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::clearPlayQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::saveQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: Play playlist Easy Jul 02 18:19:06 volumio5bern volumio[1223]: info: [GPIO_Control] Status changed: stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: [GPIO_Control] Handling event: musicStop Jul 02 18:19:06 volumio5bern volumio[1223]: info: [GPIO_Control] *** musicStop *** Jul 02 18:19:06 volumio5bern volumio[1223]: info: [GPIO_Control] Turning GPIO 4 Off (musicStop) Jul 02 18:19:06 volumio5bern volumio[1223]: info: Jul 02 18:19:06 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:19:06 volumio5bern volumio[1223]: info: sendMpdCommand stop took 12 milliseconds Jul 02 18:19:06 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:19:06 volumio5bern volumio[1223]: info: Jul 02 18:19:06 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:19:06 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:19:06 volumio5bern volumio[1223]: info: Jul 02 18:19:06 volumio5bern volumio[1223]: ---------------------------- MPD announces state update: player Jul 02 18:19:06 volumio5bern volumio[1223]: info: ControllerMpd::getState Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand status Jul 02 18:19:06 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:19:06 volumio5bern volumio[1223]: info: sendMpdCommand status took 0 milliseconds Jul 02 18:19:06 volumio5bern volumio[1223]: info: sendMpdCommand status took 1 milliseconds Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::parseState Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 02 18:19:06 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 02 18:19:06 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 02 18:19:06 volumio5bern volumio[1223]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: ControllerMpd::parseTrackInfo Jul 02 18:19:06 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: No code Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: No code Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: ControllerMpd::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::servicePushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::getTrack 8 Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"396003882&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/396003882&soundQuality=HI_RES","trackType":"tidal"} Jul 02 18:19:06 volumio5bern volumio[1223]: verbose: CURRENT POSITION 8 Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::syncState stateService stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::syncState currentStatus stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: No code Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::pushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: ------------------------------ 38ms Jul 02 18:19:06 volumio5bern volumio[1223]: info: ------------------------------ 37ms Jul 02 18:19:06 volumio5bern volumio[1223]: info: ------------------------------ 37ms Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioClearQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::ClearQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::stop Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::clearPlayQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: CorePlayQueue::saveQueue Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushState Jul 02 18:19:06 volumio5bern volumio[1223]: info: CoreCommandRouter::volumioPushQueue Jul 02 18:19:06 volumio5bern volumio[1223]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 18:19:06 volumio5bern volumio[1223]: TypeError: Cannot read property 'lastIndexOf' of undefined Jul 02 18:19:06 volumio5bern volumio[1223]: at S.startsWith (/volumio/node_modules/string/lib/string.js:427:20) Jul 02 18:19:06 volumio5bern volumio[1223]: at /volumio/app/playlistManager.js:703:25 Jul 02 18:19:06 volumio5bern volumio[1223]: at /volumio/node_modules/jsonfile/index.js:46:5 Jul 02 18:19:06 volumio5bern volumio[1223]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 Jul 02 18:19:06 volumio5bern volumio[1223]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Jul 02 18:19:06 volumio5bern volumio[1223]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 18:19:06 volumio5bern sudo[7187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 18:18 Jul 02 18:19:06 volumio5bern sudo[7187]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"